{"level":"warn","ts":"2026-02-01T18:44:22.673667Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:44:22.67375Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-01T18:44:22.673814Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-01T18:44:22.673822Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-01T18:44:22.673837Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-01T18:44:22.676154Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-02-01T18:44:22.677881Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-01T18:44:22.695058Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"16.865062ms"} {"level":"info","ts":"2026-02-01T18:44:22.755789Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-02-01T18:44:22.755859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-02-01T18:44:22.755881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-02-01T18:44:22.755917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-01T18:44:22.755925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-02-01T18:44:22.756036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-02-01T18:44:22.764421Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-01T18:44:22.773266Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-01T18:44:22.775145Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-01T18:44:22.77702Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-01T18:44:22.777274Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-01T18:44:22.777557Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-01T18:44:22.777645Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-01T18:44:22.777727Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-01T18:44:22.778145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-02-01T18:44:22.778319Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-01T18:44:22.779005Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-01T18:44:22.779192Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-01T18:44:22.779215Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-01T18:44:22.779293Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-01T18:44:22.779304Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-01T18:44:23.159725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-02-01T18:44:23.159829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-01T18:44:23.159871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-02-01T18:44:23.159918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-02-01T18:44:23.159938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-01T18:44:23.159959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-02-01T18:44:23.159993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-01T18:44:23.165078Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-02-01T18:44:23.165153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:44:23.165578Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:44:23.165743Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-01T18:44:23.16785Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-01T18:44:23.167867Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-01T18:44:23.17739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-02-01T18:44:23.177491Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:44:23.177545Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:44:23.177564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-01T18:44:23.184288Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-01T18:44:25.90843Z","caller":"traceutil/trace.go:171","msg":"trace[290047963] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"127.033865ms","start":"2026-02-01T18:44:25.781382Z","end":"2026-02-01T18:44:25.908416Z","steps":["trace[290047963] 'process raft request' (duration: 126.826884ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:44:26.247318Z","caller":"traceutil/trace.go:171","msg":"trace[701482523] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"330.949815ms","start":"2026-02-01T18:44:25.916344Z","end":"2026-02-01T18:44:26.247294Z","steps":["trace[701482523] 'read index received' (duration: 327.707087ms)","trace[701482523] 'applied index is now lower than readState.Index' (duration: 3.241518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:44:26.247494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.366628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:44:26.247531Z","caller":"traceutil/trace.go:171","msg":"trace[2055603935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"270.448969ms","start":"2026-02-01T18:44:25.977072Z","end":"2026-02-01T18:44:26.247521Z","steps":["trace[2055603935] 'agreement among raft nodes before linearized reading' (duration: 270.343458ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:44:26.24788Z","caller":"traceutil/trace.go:171","msg":"trace[969423473] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"332.941772ms","start":"2026-02-01T18:44:25.91493Z","end":"2026-02-01T18:44:26.247872Z","steps":["trace[969423473] 'process raft request' (duration: 329.16854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:26.24824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:25.91492Z","time spent":"332.982422ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:44:26.249998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.650918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:44:26.250105Z","caller":"traceutil/trace.go:171","msg":"trace[129134578] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:76; }","duration":"333.769629ms","start":"2026-02-01T18:44:25.916325Z","end":"2026-02-01T18:44:26.250095Z","steps":["trace[129134578] 'agreement among raft nodes before linearized reading' (duration: 331.037836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:26.250222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:25.916319Z","time spent":"333.89498ms","remote":"127.0.0.1:39084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-02-01T18:44:27.293123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.52738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-01T18:44:27.295056Z","caller":"traceutil/trace.go:171","msg":"trace[743688528] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"1.020051664s","start":"2026-02-01T18:44:26.274975Z","end":"2026-02-01T18:44:27.295026Z","steps":["trace[743688528] 'process raft request' (duration: 498.296595ms)","trace[743688528] 'compare' (duration: 519.421409ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:44:27.295137Z","caller":"traceutil/trace.go:171","msg":"trace[1522591130] linearizableReadLoop","detail":"{readStateIndex:83; appliedIndex:81; }","duration":"318.147778ms","start":"2026-02-01T18:44:26.976939Z","end":"2026-02-01T18:44:27.295087Z","steps":["trace[1522591130] 'read index received' (duration: 46.3µs)","trace[1522591130] 'applied index is now lower than readState.Index' (duration: 318.100758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:44:27.295159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:26.274965Z","time spent":"1.020144225s","remote":"127.0.0.1:39084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:44:27.295271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.32315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:44:27.295323Z","caller":"traceutil/trace.go:171","msg":"trace[120591591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"318.41844ms","start":"2026-02-01T18:44:26.976897Z","end":"2026-02-01T18:44:27.295315Z","steps":["trace[120591591] 'agreement among raft nodes before linearized reading' (duration: 318.34293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:27.296977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:26.976878Z","time spent":"320.086336ms","remote":"127.0.0.1:54892","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-02-01T18:44:27.295437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.945145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-01T18:44:27.29753Z","caller":"traceutil/trace.go:171","msg":"trace[1852214459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:78; }","duration":"266.074793ms","start":"2026-02-01T18:44:27.031442Z","end":"2026-02-01T18:44:27.297517Z","steps":["trace[1852214459] 'agreement among raft nodes before linearized reading' (duration: 263.937255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:27.295478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.837193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:429"} {"level":"warn","ts":"2026-02-01T18:44:27.295503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:26.771016Z","time spent":"524.486173ms","remote":"127.0.0.1:38802","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-01T18:44:27.299057Z","caller":"traceutil/trace.go:171","msg":"trace[2073439906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:78; }","duration":"261.423193ms","start":"2026-02-01T18:44:27.037624Z","end":"2026-02-01T18:44:27.299047Z","steps":["trace[2073439906] 'agreement among raft nodes before linearized reading' (duration: 257.837893ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:44:27.683321Z","caller":"traceutil/trace.go:171","msg":"trace[497912224] linearizableReadLoop","detail":"{readStateIndex:84; appliedIndex:83; }","duration":"383.4135ms","start":"2026-02-01T18:44:27.299885Z","end":"2026-02-01T18:44:27.683298Z","steps":["trace[497912224] 'read index received' (duration: 306.99624ms)","trace[497912224] 'applied index is now lower than readState.Index' (duration: 76.41634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:44:27.683486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.581202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-01T18:44:27.683518Z","caller":"traceutil/trace.go:171","msg":"trace[2044465099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:79; }","duration":"383.637773ms","start":"2026-02-01T18:44:27.29987Z","end":"2026-02-01T18:44:27.683508Z","steps":["trace[2044465099] 'agreement among raft nodes before linearized reading' (duration: 383.523002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:27.683548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:27.299864Z","time spent":"383.676594ms","remote":"127.0.0.1:54898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:44:27.683616Z","caller":"traceutil/trace.go:171","msg":"trace[1458236643] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"384.034366ms","start":"2026-02-01T18:44:27.299562Z","end":"2026-02-01T18:44:27.683597Z","steps":["trace[1458236643] 'process raft request' (duration: 307.221523ms)","trace[1458236643] 'compare' (duration: 76.40652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:44:27.683723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:27.299552Z","time spent":"384.101966ms","remote":"127.0.0.1:39084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:44:27.685764Z","caller":"traceutil/trace.go:171","msg":"trace[1169410903] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"381.473864ms","start":"2026-02-01T18:44:27.30428Z","end":"2026-02-01T18:44:27.685754Z","steps":["trace[1169410903] 'process raft request' (duration: 381.449994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:27.685956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:27.304274Z","time spent":"381.522225ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:44:27.686192Z","caller":"traceutil/trace.go:171","msg":"trace[122168283] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"383.706093ms","start":"2026-02-01T18:44:27.302478Z","end":"2026-02-01T18:44:27.686184Z","steps":["trace[122168283] 'process raft request' (duration: 383.171348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:27.686235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:27.302469Z","time spent":"383.745634ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:44:27.686318Z","caller":"traceutil/trace.go:171","msg":"trace[25766535] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"383.770564ms","start":"2026-02-01T18:44:27.302542Z","end":"2026-02-01T18:44:27.686312Z","steps":["trace[25766535] 'process raft request' (duration: 383.161639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:44:27.686353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:27.30253Z","time spent":"383.806034ms","remote":"127.0.0.1:38802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-01T18:44:34.383339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.185938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-01T18:44:34.383499Z","caller":"traceutil/trace.go:171","msg":"trace[898272975] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:391; }","duration":"122.35922ms","start":"2026-02-01T18:44:34.261084Z","end":"2026-02-01T18:44:34.383443Z","steps":["trace[898272975] 'range keys from in-memory index tree' (duration: 122.078118ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:44:53.045962Z","caller":"traceutil/trace.go:171","msg":"trace[698558252] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"223.412482ms","start":"2026-02-01T18:44:52.822522Z","end":"2026-02-01T18:44:53.045935Z","steps":["trace[698558252] 'process raft request' (duration: 223.16751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:01.171801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51311334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:45:01.171942Z","caller":"traceutil/trace.go:171","msg":"trace[1492694074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"1.513254981s","start":"2026-02-01T18:44:59.65863Z","end":"2026-02-01T18:45:01.171885Z","steps":["trace[1492694074] 'range keys from in-memory index tree' (duration: 1.513031109s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:01.172004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:59.658615Z","time spent":"1.513375122s","remote":"127.0.0.1:54898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:45:01.172439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.325079771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-01T18:45:01.172536Z","caller":"traceutil/trace.go:171","msg":"trace[607819287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"1.325142581s","start":"2026-02-01T18:44:59.847321Z","end":"2026-02-01T18:45:01.172464Z","steps":["trace[607819287] 'range keys from in-memory index tree' (duration: 1.32492062s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:01.1726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:59.847305Z","time spent":"1.325257872s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:45:01.172903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298896607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T18:45:01.173065Z","caller":"traceutil/trace.go:171","msg":"trace[82239407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"1.299088708s","start":"2026-02-01T18:44:59.873962Z","end":"2026-02-01T18:45:01.173051Z","steps":["trace[82239407] 'range keys from in-memory index tree' (duration: 1.298741426s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:01.173106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:59.873944Z","time spent":"1.299149808s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:45:01.173291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.191652134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nzdsc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-01T18:45:01.17332Z","caller":"traceutil/trace.go:171","msg":"trace[1763331479] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nzdsc; range_end:; response_count:1; response_revision:551; }","duration":"1.191719555s","start":"2026-02-01T18:44:59.98159Z","end":"2026-02-01T18:45:01.17331Z","steps":["trace[1763331479] 'range keys from in-memory index tree' (duration: 1.191528103s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:01.173347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:44:59.981567Z","time spent":"1.191774745s","remote":"127.0.0.1:38910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-nzdsc\" "} {"level":"warn","ts":"2026-02-01T18:45:01.173739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:00.587888Z","time spent":"585.848948ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-01T18:45:01.349808Z","caller":"traceutil/trace.go:171","msg":"trace[857056170] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"665.626561ms","start":"2026-02-01T18:45:00.684161Z","end":"2026-02-01T18:45:01.349788Z","steps":["trace[857056170] 'process raft request' (duration: 665.48498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:01.349953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:00.684145Z","time spent":"665.735791ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:45:02.804574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.455167691s","expected-duration":"1s"} {"level":"info","ts":"2026-02-01T18:45:02.805008Z","caller":"traceutil/trace.go:171","msg":"trace[432131679] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:569; }","duration":"1.726117303s","start":"2026-02-01T18:45:01.078875Z","end":"2026-02-01T18:45:02.804993Z","steps":["trace[432131679] 'read index received' (duration: 270.685031ms)","trace[432131679] 'applied index is now lower than readState.Index' (duration: 1.455431302s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:45:02.805201Z","caller":"traceutil/trace.go:171","msg":"trace[1677303668] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"2.110410182s","start":"2026-02-01T18:45:00.694785Z","end":"2026-02-01T18:45:02.805195Z","steps":["trace[1677303668] 'process raft request' (duration: 2.109916769s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:02.805268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:00.694764Z","time spent":"2.110456243s","remote":"127.0.0.1:38900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4345,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:45:02.805406Z","caller":"traceutil/trace.go:171","msg":"trace[168867786] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.626297227s","start":"2026-02-01T18:45:01.179103Z","end":"2026-02-01T18:45:02.805401Z","steps":["trace[168867786] 'process raft request' (duration: 1.625801514s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:02.805436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:01.179093Z","time spent":"1.626329297s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:45:02.80549Z","caller":"traceutil/trace.go:171","msg":"trace[888716462] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.621212594s","start":"2026-02-01T18:45:01.184272Z","end":"2026-02-01T18:45:02.805485Z","steps":["trace[888716462] 'process raft request' (duration: 1.62066048s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:02.805517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:01.184265Z","time spent":"1.621239394s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:45:02.805611Z","caller":"traceutil/trace.go:171","msg":"trace[1832840978] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.617625989s","start":"2026-02-01T18:45:01.187981Z","end":"2026-02-01T18:45:02.805607Z","steps":["trace[1832840978] 'process raft request' (duration: 1.616970835s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:02.805639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:01.187972Z","time spent":"1.61765394s","remote":"127.0.0.1:38910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:45:02.805733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.726864978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-01T18:45:02.805753Z","caller":"traceutil/trace.go:171","msg":"trace[1512461886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"1.726911568s","start":"2026-02-01T18:45:01.078837Z","end":"2026-02-01T18:45:02.805748Z","steps":["trace[1512461886] 'agreement among raft nodes before linearized reading' (duration: 1.726850368s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:02.805782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:01.078821Z","time spent":"1.726957879s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-01T18:45:02.805839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619026919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:45:02.805855Z","caller":"traceutil/trace.go:171","msg":"trace[1600940279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"1.619043159s","start":"2026-02-01T18:45:01.186808Z","end":"2026-02-01T18:45:02.805851Z","steps":["trace[1600940279] 'agreement among raft nodes before linearized reading' (duration: 1.619019799s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:02.805866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:01.186786Z","time spent":"1.619077779s","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:45:02.80609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482244498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-01T18:45:02.806107Z","caller":"traceutil/trace.go:171","msg":"trace[1121892727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"1.48231323s","start":"2026-02-01T18:45:01.32379Z","end":"2026-02-01T18:45:02.806104Z","steps":["trace[1121892727] 'agreement among raft nodes before linearized reading' (duration: 1.482242749s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:02.80612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:01.323772Z","time spent":"1.48234455s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-01T18:45:03.930028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049791519s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:45:03.930246Z","caller":"traceutil/trace.go:171","msg":"trace[1172195853] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.105782914s","start":"2026-02-01T18:45:02.824444Z","end":"2026-02-01T18:45:03.930226Z","steps":["trace[1172195853] 'process raft request' (duration: 55.707173ms)","trace[1172195853] 'compare' (duration: 1.049676878s)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:45:03.930258Z","caller":"traceutil/trace.go:171","msg":"trace[1829558084] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"1.084764238s","start":"2026-02-01T18:45:02.845326Z","end":"2026-02-01T18:45:03.93009Z","steps":["trace[1829558084] 'read index received' (duration: 34.833769ms)","trace[1829558084] 'applied index is now lower than readState.Index' (duration: 1.049929539s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:45:03.930373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:02.824431Z","time spent":"1.105852554s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:45:03.930316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08498637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:45:03.930462Z","caller":"traceutil/trace.go:171","msg":"trace[537667201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"1.08515489s","start":"2026-02-01T18:45:02.845296Z","end":"2026-02-01T18:45:03.930451Z","steps":["trace[537667201] 'agreement among raft nodes before linearized reading' (duration: 1.084990589s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:03.930518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:02.845283Z","time spent":"1.085202051s","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:45:04.279234Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.399197437s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-01T18:45:04.492153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.683181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:45:04.492394Z","caller":"traceutil/trace.go:171","msg":"trace[570335144] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.645561789s","start":"2026-02-01T18:45:02.84682Z","end":"2026-02-01T18:45:04.492381Z","steps":["trace[570335144] 'process raft request' (duration: 1.432601067s)","trace[570335144] 'compare' (duration: 212.61576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:45:04.492438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:02.846811Z","time spent":"1.645609839s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:45:04.495584Z","caller":"traceutil/trace.go:171","msg":"trace[759151520] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"563.974221ms","start":"2026-02-01T18:45:03.931602Z","end":"2026-02-01T18:45:04.495577Z","steps":["trace[759151520] 'read index received' (duration: 347.805788ms)","trace[759151520] 'applied index is now lower than readState.Index' (duration: 216.167853ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:45:04.495622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.017052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:45:04.495641Z","caller":"traceutil/trace.go:171","msg":"trace[1425185073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"564.049932ms","start":"2026-02-01T18:45:03.931581Z","end":"2026-02-01T18:45:04.49563Z","steps":["trace[1425185073] 'agreement among raft nodes before linearized reading' (duration: 564.024442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:04.495659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:45:03.931569Z","time spent":"564.086622ms","remote":"127.0.0.1:54898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:45:07.446239Z","caller":"traceutil/trace.go:171","msg":"trace[1173322104] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"149.081105ms","start":"2026-02-01T18:45:07.297141Z","end":"2026-02-01T18:45:07.446222Z","steps":["trace[1173322104] 'process raft request' (duration: 148.970175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:09.420098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.768246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-01T18:45:09.420158Z","caller":"traceutil/trace.go:171","msg":"trace[1687860155] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:629; }","duration":"124.877161ms","start":"2026-02-01T18:45:09.295265Z","end":"2026-02-01T18:45:09.420142Z","steps":["trace[1687860155] 'range keys from in-memory index tree' (duration: 124.599977ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:45:50.825494Z","caller":"traceutil/trace.go:171","msg":"trace[814337505] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"104.482484ms","start":"2026-02-01T18:45:50.720991Z","end":"2026-02-01T18:45:50.825473Z","steps":["trace[814337505] 'process raft request' (duration: 104.35058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:45:55.91663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.833797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:45:55.917788Z","caller":"traceutil/trace.go:171","msg":"trace[1394176399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"258.014882ms","start":"2026-02-01T18:45:55.659709Z","end":"2026-02-01T18:45:55.917723Z","steps":["trace[1394176399] 'range keys from in-memory index tree' (duration: 256.723953ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:08.450816Z","caller":"traceutil/trace.go:171","msg":"trace[1894807956] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"102.547294ms","start":"2026-02-01T18:46:08.348252Z","end":"2026-02-01T18:46:08.450799Z","steps":["trace[1894807956] 'read index received' (duration: 53.020657ms)","trace[1894807956] 'applied index is now lower than readState.Index' (duration: 49.525737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:08.450969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.697297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4912"} {"level":"info","ts":"2026-02-01T18:46:08.450991Z","caller":"traceutil/trace.go:171","msg":"trace[1217329027] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:899; }","duration":"102.756789ms","start":"2026-02-01T18:46:08.348225Z","end":"2026-02-01T18:46:08.450981Z","steps":["trace[1217329027] 'agreement among raft nodes before linearized reading' (duration: 102.638956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:23.607454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.486293ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:46:23.608294Z","caller":"traceutil/trace.go:171","msg":"trace[1028127847] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"209.050371ms","start":"2026-02-01T18:46:23.3992Z","end":"2026-02-01T18:46:23.608251Z","steps":["trace[1028127847] 'process raft request' (duration: 68.3311ms)","trace[1028127847] 'compare' (duration: 137.503499ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:23.708604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.680254ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-01T18:46:23.708938Z","caller":"traceutil/trace.go:171","msg":"trace[800904538] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"248.652178ms","start":"2026-02-01T18:46:23.460266Z","end":"2026-02-01T18:46:23.708918Z","steps":["trace[800904538] 'read index received' (duration: 7.352815ms)","trace[800904538] 'applied index is now lower than readState.Index' (duration: 241.297193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:23.709415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.206263ms","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":"warn","ts":"2026-02-01T18:46:23.709385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.061256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-01T18:46:23.709462Z","caller":"traceutil/trace.go:171","msg":"trace[1523784356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"217.316436ms","start":"2026-02-01T18:46:23.492133Z","end":"2026-02-01T18:46:23.70945Z","steps":["trace[1523784356] 'agreement among raft nodes before linearized reading' (duration: 217.06509ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:23.709653Z","caller":"traceutil/trace.go:171","msg":"trace[752328612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"249.419355ms","start":"2026-02-01T18:46:23.460207Z","end":"2026-02-01T18:46:23.709627Z","steps":["trace[752328612] 'agreement among raft nodes before linearized reading' (duration: 248.77421ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:26.03469Z","caller":"traceutil/trace.go:171","msg":"trace[81128658] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"279.13449ms","start":"2026-02-01T18:46:25.755531Z","end":"2026-02-01T18:46:26.034666Z","steps":["trace[81128658] 'process raft request' (duration: 278.994087ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:26.116368Z","caller":"traceutil/trace.go:171","msg":"trace[1006672831] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"164.169385ms","start":"2026-02-01T18:46:25.952172Z","end":"2026-02-01T18:46:26.116342Z","steps":["trace[1006672831] 'read index received' (duration: 82.759027ms)","trace[1006672831] 'applied index is now lower than readState.Index' (duration: 81.408848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:26.116694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.511752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-02-01T18:46:26.116787Z","caller":"traceutil/trace.go:171","msg":"trace[1534714815] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:981; }","duration":"164.651655ms","start":"2026-02-01T18:46:25.952124Z","end":"2026-02-01T18:46:26.116776Z","steps":["trace[1534714815] 'agreement among raft nodes before linearized reading' (duration: 164.4318ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:26.117054Z","caller":"traceutil/trace.go:171","msg":"trace[950950403] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"358.899571ms","start":"2026-02-01T18:46:25.758143Z","end":"2026-02-01T18:46:26.117043Z","steps":["trace[950950403] 'process raft request' (duration: 351.06951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:26.117214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:25.758125Z","time spent":"359.030734ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:29.318492Z","caller":"traceutil/trace.go:171","msg":"trace[1201336652] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"312.894027ms","start":"2026-02-01T18:46:29.005591Z","end":"2026-02-01T18:46:29.318486Z","steps":["trace[1201336652] 'process raft request' (duration: 312.743383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:29.318652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:29.005575Z","time spent":"313.02824ms","remote":"127.0.0.1:38802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":778,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-01T18:46:29.318445Z","caller":"traceutil/trace.go:171","msg":"trace[1625618028] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"172.779892ms","start":"2026-02-01T18:46:29.145651Z","end":"2026-02-01T18:46:29.318431Z","steps":["trace[1625618028] 'read index received' (duration: 172.616118ms)","trace[1625618028] 'applied index is now lower than readState.Index' (duration: 163.214µs)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:29.319657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.000938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-01T18:46:29.319793Z","caller":"traceutil/trace.go:171","msg":"trace[968538459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"174.172861ms","start":"2026-02-01T18:46:29.145612Z","end":"2026-02-01T18:46:29.319784Z","steps":["trace[968538459] 'agreement among raft nodes before linearized reading' (duration: 173.370044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:30.576828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.906403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:46:30.576893Z","caller":"traceutil/trace.go:171","msg":"trace[1031907247] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"236.914917ms","start":"2026-02-01T18:46:30.339966Z","end":"2026-02-01T18:46:30.576881Z","steps":["trace[1031907247] 'read index received' (duration: 55.081µs)","trace[1031907247] 'applied index is now lower than readState.Index' (duration: 236.859036ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:46:30.577036Z","caller":"traceutil/trace.go:171","msg":"trace[1569812391] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"430.647552ms","start":"2026-02-01T18:46:30.146381Z","end":"2026-02-01T18:46:30.577028Z","steps":["trace[1569812391] 'process raft request' (duration: 100.491124ms)","trace[1569812391] 'compare' (duration: 329.656398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:30.577075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:30.146323Z","time spent":"430.732154ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:46:30.577246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.286486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-02-01T18:46:30.577265Z","caller":"traceutil/trace.go:171","msg":"trace[336407639] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:996; }","duration":"237.307046ms","start":"2026-02-01T18:46:30.339951Z","end":"2026-02-01T18:46:30.577259Z","steps":["trace[336407639] 'agreement among raft nodes before linearized reading' (duration: 237.223445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:30.577394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.670384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-01T18:46:30.57741Z","caller":"traceutil/trace.go:171","msg":"trace[515460987] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:996; }","duration":"228.700704ms","start":"2026-02-01T18:46:30.348705Z","end":"2026-02-01T18:46:30.577405Z","steps":["trace[515460987] 'agreement among raft nodes before linearized reading' (duration: 228.648663ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:30.792995Z","caller":"traceutil/trace.go:171","msg":"trace[587320351] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"204.932282ms","start":"2026-02-01T18:46:30.588041Z","end":"2026-02-01T18:46:30.792973Z","steps":["trace[587320351] 'read index received' (duration: 118.285281ms)","trace[587320351] 'applied index is now lower than readState.Index' (duration: 86.646091ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:46:30.793193Z","caller":"traceutil/trace.go:171","msg":"trace[1997656569] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"205.195788ms","start":"2026-02-01T18:46:30.587959Z","end":"2026-02-01T18:46:30.793155Z","steps":["trace[1997656569] 'process raft request' (duration: 204.925062ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:30.7932Z","caller":"traceutil/trace.go:171","msg":"trace[645633002] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"205.397301ms","start":"2026-02-01T18:46:30.587774Z","end":"2026-02-01T18:46:30.793171Z","steps":["trace[645633002] 'process raft request' (duration: 118.538225ms)","trace[645633002] 'compare' (duration: 86.402126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:30.793585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.524474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-30d0216a-17d5-4fce-b7ee-8735839841b7\" ","response":"range_response_count:1 size:1254"} {"level":"info","ts":"2026-02-01T18:46:30.793624Z","caller":"traceutil/trace.go:171","msg":"trace[2102326699] range","detail":"{range_begin:/registry/persistentvolumes/pvc-30d0216a-17d5-4fce-b7ee-8735839841b7; range_end:; response_count:1; response_revision:998; }","duration":"205.584525ms","start":"2026-02-01T18:46:30.588025Z","end":"2026-02-01T18:46:30.793609Z","steps":["trace[2102326699] 'agreement among raft nodes before linearized reading' (duration: 205.462813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:30.793792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.72992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:46:30.793942Z","caller":"traceutil/trace.go:171","msg":"trace[227860897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"110.834423ms","start":"2026-02-01T18:46:30.682992Z","end":"2026-02-01T18:46:30.793826Z","steps":["trace[227860897] 'agreement among raft nodes before linearized reading' (duration: 110.728341ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:31.531129Z","caller":"traceutil/trace.go:171","msg":"trace[1644513243] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"125.638734ms","start":"2026-02-01T18:46:31.405473Z","end":"2026-02-01T18:46:31.531112Z","steps":["trace[1644513243] 'read index received' (duration: 125.495031ms)","trace[1644513243] 'applied index is now lower than readState.Index' (duration: 142.993µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:46:31.531429Z","caller":"traceutil/trace.go:171","msg":"trace[1553499690] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"154.749975ms","start":"2026-02-01T18:46:31.376669Z","end":"2026-02-01T18:46:31.531419Z","steps":["trace[1553499690] 'process raft request' (duration: 154.349796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:31.532407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.931362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-01T18:46:31.532443Z","caller":"traceutil/trace.go:171","msg":"trace[723242715] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:999; }","duration":"127.003883ms","start":"2026-02-01T18:46:31.405408Z","end":"2026-02-01T18:46:31.532434Z","steps":["trace[723242715] 'agreement among raft nodes before linearized reading' (duration: 125.753287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:33.253989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.877304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-01T18:46:33.254225Z","caller":"traceutil/trace.go:171","msg":"trace[919781765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"668.121639ms","start":"2026-02-01T18:46:32.586086Z","end":"2026-02-01T18:46:33.254207Z","steps":["trace[919781765] 'range keys from in-memory index tree' (duration: 667.737301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:33.254359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:32.586029Z","time spent":"668.315083ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-01T18:46:33.254856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.896554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:46:33.25667Z","caller":"traceutil/trace.go:171","msg":"trace[201361657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"594.729601ms","start":"2026-02-01T18:46:32.66193Z","end":"2026-02-01T18:46:33.25666Z","steps":["trace[201361657] 'range keys from in-memory index tree' (duration: 592.860143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:33.256815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:32.661919Z","time spent":"594.877184ms","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:46:33.257114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.61543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-01T18:46:33.257722Z","caller":"traceutil/trace.go:171","msg":"trace[193960044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"458.224652ms","start":"2026-02-01T18:46:32.799488Z","end":"2026-02-01T18:46:33.257713Z","steps":["trace[193960044] 'range keys from in-memory index tree' (duration: 457.526718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:33.257822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:32.799424Z","time spent":"458.385505ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-01T18:46:33.258434Z","caller":"traceutil/trace.go:171","msg":"trace[769671627] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"297.428466ms","start":"2026-02-01T18:46:32.960996Z","end":"2026-02-01T18:46:33.258425Z","steps":["trace[769671627] 'read index received' (duration: 232.501576ms)","trace[769671627] 'applied index is now lower than readState.Index' (duration: 64.92624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:33.259801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.797115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:46:33.259907Z","caller":"traceutil/trace.go:171","msg":"trace[816800776] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1004; }","duration":"298.941057ms","start":"2026-02-01T18:46:32.960955Z","end":"2026-02-01T18:46:33.259896Z","steps":["trace[816800776] 'agreement among raft nodes before linearized reading' (duration: 298.796434ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:33.260171Z","caller":"traceutil/trace.go:171","msg":"trace[161469574] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"382.300012ms","start":"2026-02-01T18:46:32.877862Z","end":"2026-02-01T18:46:33.260162Z","steps":["trace[161469574] 'process raft request' (duration: 315.687757ms)","trace[161469574] 'compare' (duration: 64.624434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:33.260933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:32.87784Z","time spent":"383.043307ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:46:34.588378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227476813s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:46:34.588602Z","caller":"traceutil/trace.go:171","msg":"trace[1670168421] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.322451503s","start":"2026-02-01T18:46:33.266121Z","end":"2026-02-01T18:46:34.588572Z","steps":["trace[1670168421] 'process raft request' (duration: 94.728845ms)","trace[1670168421] 'compare' (duration: 1.226637416s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:34.58877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:33.266112Z","time spent":"1.322580356s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:34.589122Z","caller":"traceutil/trace.go:171","msg":"trace[122491026] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"1.322897673s","start":"2026-02-01T18:46:33.266209Z","end":"2026-02-01T18:46:34.589106Z","steps":["trace[122491026] 'process raft request' (duration: 1.32226687s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:34.589188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:33.266203Z","time spent":"1.322950354s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:46:34.886108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.535234ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-01T18:46:34.886321Z","caller":"traceutil/trace.go:171","msg":"trace[1794551924] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1042; }","duration":"1.351165898s","start":"2026-02-01T18:46:33.53514Z","end":"2026-02-01T18:46:34.886306Z","steps":["trace[1794551924] 'read index received' (duration: 1.923089ms)","trace[1794551924] 'applied index is now lower than readState.Index' (duration: 1.349241969s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:34.886798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351642868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-01T18:46:34.88684Z","caller":"traceutil/trace.go:171","msg":"trace[355450366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"1.35173166s","start":"2026-02-01T18:46:33.535098Z","end":"2026-02-01T18:46:34.88683Z","steps":["trace[355450366] 'agreement among raft nodes before linearized reading' (duration: 1.351594577s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:34.886901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:33.535079Z","time spent":"1.351813821s","remote":"127.0.0.1:39004","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":"info","ts":"2026-02-01T18:46:34.889119Z","caller":"traceutil/trace.go:171","msg":"trace[809737151] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"402.364051ms","start":"2026-02-01T18:46:34.486728Z","end":"2026-02-01T18:46:34.889092Z","steps":["trace[809737151] 'process raft request' (duration: 399.461182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:34.889532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:34.486702Z","time spent":"402.682718ms","remote":"127.0.0.1:38900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5218,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:46:36.093614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.414693873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-01T18:46:36.093811Z","caller":"traceutil/trace.go:171","msg":"trace[435840134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"2.414920738s","start":"2026-02-01T18:46:33.678865Z","end":"2026-02-01T18:46:36.093786Z","steps":["trace[435840134] 'agreement among raft nodes before linearized reading' (duration: 1.210200078s)","trace[435840134] 'range keys from in-memory index tree' (duration: 1.204360582s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.093898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:33.67885Z","time spent":"2.41503344s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-01T18:46:36.094053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89319689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-01T18:46:36.094126Z","caller":"traceutil/trace.go:171","msg":"trace[30022708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"1.893312122s","start":"2026-02-01T18:46:34.200795Z","end":"2026-02-01T18:46:36.094107Z","steps":["trace[30022708] 'agreement among raft nodes before linearized reading' (duration: 688.861658ms)","trace[30022708] 'range keys from in-memory index tree' (duration: 1.204232659s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.094172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:34.200776Z","time spent":"1.893387724s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-01T18:46:36.094234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.10014653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:46:36.094298Z","caller":"traceutil/trace.go:171","msg":"trace[296101128] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1007; }","duration":"2.100264643s","start":"2026-02-01T18:46:33.994016Z","end":"2026-02-01T18:46:36.094281Z","steps":["trace[296101128] 'agreement among raft nodes before linearized reading' (duration: 895.651066ms)","trace[296101128] 'count revisions from in-memory index tree' (duration: 1.204510935s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.094357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:33.993998Z","time spent":"2.100345594s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:46:36.094463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794927778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:46:36.094505Z","caller":"traceutil/trace.go:171","msg":"trace[65609631] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1007; }","duration":"1.795007659s","start":"2026-02-01T18:46:34.299485Z","end":"2026-02-01T18:46:36.094493Z","steps":["trace[65609631] 'agreement among raft nodes before linearized reading' (duration: 590.145427ms)","trace[65609631] 'count revisions from in-memory index tree' (duration: 1.204803281s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.094536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:34.299463Z","time spent":"1.795064221s","remote":"127.0.0.1:38954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-01T18:46:36.094611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188482566s","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-02-01T18:46:36.099837Z","caller":"traceutil/trace.go:171","msg":"trace[1511989786] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1007; }","duration":"1.193705362s","start":"2026-02-01T18:46:34.906116Z","end":"2026-02-01T18:46:36.099822Z","steps":["trace[1511989786] 'range keys from in-memory index tree' (duration: 1.188474726s)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:36.094671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434637318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-01T18:46:36.095088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130858896s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:46:36.100149Z","caller":"traceutil/trace.go:171","msg":"trace[2003606246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"1.44014301s","start":"2026-02-01T18:46:34.659992Z","end":"2026-02-01T18:46:36.100135Z","steps":["trace[2003606246] 'agreement among raft nodes before linearized reading' (duration: 229.655224ms)","trace[2003606246] 'range keys from in-memory index tree' (duration: 1.205007265s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.100197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:34.659974Z","time spent":"1.440211541s","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-01T18:46:36.1001Z","caller":"traceutil/trace.go:171","msg":"trace[882166036] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"1.207013555s","start":"2026-02-01T18:46:34.893071Z","end":"2026-02-01T18:46:36.100085Z","steps":["trace[882166036] 'process raft request' (duration: 71.104567ms)","trace[882166036] 'compare' (duration: 1.130784824s)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.101095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:34.893052Z","time spent":"1.207347571s","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-01T18:46:36.742556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.609502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:46:36.742734Z","caller":"traceutil/trace.go:171","msg":"trace[1924007468] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"627.848683ms","start":"2026-02-01T18:46:36.114872Z","end":"2026-02-01T18:46:36.74272Z","steps":["trace[1924007468] 'process raft request' (duration: 627.762041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:36.742783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:36.114867Z","time spent":"627.894563ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:36.743021Z","caller":"traceutil/trace.go:171","msg":"trace[1393947110] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"628.446945ms","start":"2026-02-01T18:46:36.114566Z","end":"2026-02-01T18:46:36.743013Z","steps":["trace[1393947110] 'process raft request' (duration: 95.319252ms)","trace[1393947110] 'compare' (duration: 532.473869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.743059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:36.114547Z","time spent":"628.490336ms","remote":"127.0.0.1:39004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-01T18:46:36.743631Z","caller":"traceutil/trace.go:171","msg":"trace[1358893617] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"628.897464ms","start":"2026-02-01T18:46:36.114725Z","end":"2026-02-01T18:46:36.743623Z","steps":["trace[1358893617] 'read index received' (duration: 95.116959ms)","trace[1358893617] 'applied index is now lower than readState.Index' (duration: 533.779555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:46:36.744202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.469455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:46:36.744229Z","caller":"traceutil/trace.go:171","msg":"trace[1986610606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"629.522407ms","start":"2026-02-01T18:46:36.114699Z","end":"2026-02-01T18:46:36.744222Z","steps":["trace[1986610606] 'agreement among raft nodes before linearized reading' (duration: 629.469216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:36.744247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-01T18:46:36.114693Z","time spent":"629.550157ms","remote":"127.0.0.1:54892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-01T18:46:36.746634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.779451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-01T18:46:36.746662Z","caller":"traceutil/trace.go:171","msg":"trace[977908658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"152.836492ms","start":"2026-02-01T18:46:36.593818Z","end":"2026-02-01T18:46:36.746654Z","steps":["trace[977908658] 'agreement among raft nodes before linearized reading' (duration: 152.73702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:36.746805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.843953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-01T18:46:36.746824Z","caller":"traceutil/trace.go:171","msg":"trace[1287809449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"152.872973ms","start":"2026-02-01T18:46:36.593945Z","end":"2026-02-01T18:46:36.746818Z","steps":["trace[1287809449] 'agreement among raft nodes before linearized reading' (duration: 152.817462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:36.959784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.945118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-01T18:46:36.959869Z","caller":"traceutil/trace.go:171","msg":"trace[1363142816] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"208.510375ms","start":"2026-02-01T18:46:36.751345Z","end":"2026-02-01T18:46:36.959855Z","steps":["trace[1363142816] 'process raft request' (duration: 97.436725ms)","trace[1363142816] 'compare' (duration: 110.821465ms)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:46:36.967198Z","caller":"traceutil/trace.go:171","msg":"trace[963466304] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"211.315932ms","start":"2026-02-01T18:46:36.755863Z","end":"2026-02-01T18:46:36.967179Z","steps":["trace[963466304] 'process raft request' (duration: 211.201869ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:37.775926Z","caller":"traceutil/trace.go:171","msg":"trace[1088467213] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"154.611764ms","start":"2026-02-01T18:46:37.621285Z","end":"2026-02-01T18:46:37.775896Z","steps":["trace[1088467213] 'read index received' (duration: 154.424671ms)","trace[1088467213] 'applied index is now lower than readState.Index' (duration: 186.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:46:37.776274Z","caller":"traceutil/trace.go:171","msg":"trace[101748785] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"155.397441ms","start":"2026-02-01T18:46:37.620862Z","end":"2026-02-01T18:46:37.776259Z","steps":["trace[101748785] 'process raft request' (duration: 154.87706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:37.776767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.588994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1780"} {"level":"info","ts":"2026-02-01T18:46:37.776944Z","caller":"traceutil/trace.go:171","msg":"trace[1683877443] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:1015; }","duration":"134.801868ms","start":"2026-02-01T18:46:37.642124Z","end":"2026-02-01T18:46:37.776926Z","steps":["trace[1683877443] 'agreement among raft nodes before linearized reading' (duration: 134.444311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:37.777287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.993043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-01T18:46:37.777459Z","caller":"traceutil/trace.go:171","msg":"trace[1465009715] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1015; }","duration":"156.195436ms","start":"2026-02-01T18:46:37.62125Z","end":"2026-02-01T18:46:37.777446Z","steps":["trace[1465009715] 'agreement among raft nodes before linearized reading' (duration: 155.994752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:37.777886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.294798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2775"} {"level":"info","ts":"2026-02-01T18:46:37.777998Z","caller":"traceutil/trace.go:171","msg":"trace[340221732] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1015; }","duration":"101.457541ms","start":"2026-02-01T18:46:37.676523Z","end":"2026-02-01T18:46:37.777981Z","steps":["trace[340221732] 'agreement among raft nodes before linearized reading' (duration: 101.287038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:37.778433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.034403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:46:37.778591Z","caller":"traceutil/trace.go:171","msg":"trace[1085373769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"117.266447ms","start":"2026-02-01T18:46:37.661312Z","end":"2026-02-01T18:46:37.778579Z","steps":["trace[1085373769] 'agreement among raft nodes before linearized reading' (duration: 117.085714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:41.036856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.584085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/local-path-provisioner\" ","response":"range_response_count:1 size:1106"} {"level":"info","ts":"2026-02-01T18:46:41.036905Z","caller":"traceutil/trace.go:171","msg":"trace[181183136] range","detail":"{range_begin:/registry/clusterroles/local-path-provisioner; range_end:; response_count:1; response_revision:1026; }","duration":"142.665338ms","start":"2026-02-01T18:46:40.894229Z","end":"2026-02-01T18:46:41.036894Z","steps":["trace[181183136] 'range keys from in-memory index tree' (duration: 142.481804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:41.037164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.729311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-02-01T18:46:41.037742Z","caller":"traceutil/trace.go:171","msg":"trace[1456156753] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1026; }","duration":"113.280082ms","start":"2026-02-01T18:46:40.924394Z","end":"2026-02-01T18:46:41.037674Z","steps":["trace[1456156753] 'range keys from in-memory index tree' (duration: 112.623569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:43.360373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.357369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:695"} {"level":"info","ts":"2026-02-01T18:46:43.361421Z","caller":"traceutil/trace.go:171","msg":"trace[1186381871] range","detail":"{range_begin:/registry/clusterroles/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1033; }","duration":"152.445989ms","start":"2026-02-01T18:46:43.208958Z","end":"2026-02-01T18:46:43.361404Z","steps":["trace[1186381871] 'range keys from in-memory index tree' (duration: 151.301387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:43.36087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.934896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium\" ","response":"range_response_count:1 size:749"} {"level":"info","ts":"2026-02-01T18:46:43.362206Z","caller":"traceutil/trace.go:171","msg":"trace[100917337] range","detail":"{range_begin:/registry/clusterrolebindings/cilium; range_end:; response_count:1; response_revision:1033; }","duration":"146.258031ms","start":"2026-02-01T18:46:43.215915Z","end":"2026-02-01T18:46:43.362173Z","steps":["trace[100917337] 'range keys from in-memory index tree' (duration: 144.827353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:46:45.191099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.860788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-02-01T18:46:45.19116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.860323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-02-01T18:46:45.191225Z","caller":"traceutil/trace.go:171","msg":"trace[1624944379] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1037; }","duration":"110.946855ms","start":"2026-02-01T18:46:45.080251Z","end":"2026-02-01T18:46:45.191198Z","steps":["trace[1624944379] 'range keys from in-memory index tree' (duration: 110.810301ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:46:45.191172Z","caller":"traceutil/trace.go:171","msg":"trace[288300131] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1037; }","duration":"150.96505ms","start":"2026-02-01T18:46:45.040189Z","end":"2026-02-01T18:46:45.191154Z","steps":["trace[288300131] 'range keys from in-memory index tree' (duration: 150.797477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:03.587284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.123955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T18:47:03.587583Z","caller":"traceutil/trace.go:171","msg":"trace[1878938966] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:1089; }","duration":"136.43994ms","start":"2026-02-01T18:47:03.451126Z","end":"2026-02-01T18:47:03.587566Z","steps":["trace[1878938966] 'range keys from in-memory index tree' (duration: 136.035973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:03.601797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.601019ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-01T18:47:03.601884Z","caller":"traceutil/trace.go:171","msg":"trace[212962731] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"121.276706ms","start":"2026-02-01T18:47:03.480595Z","end":"2026-02-01T18:47:03.601872Z","steps":["trace[212962731] 'read index received' (duration: 14.560224ms)","trace[212962731] 'applied index is now lower than readState.Index' (duration: 106.715122ms)"],"step_count":2} {"level":"warn","ts":"2026-02-01T18:47:03.601971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.367627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T18:47:03.60201Z","caller":"traceutil/trace.go:171","msg":"trace[1723927378] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:1089; }","duration":"121.417997ms","start":"2026-02-01T18:47:03.480569Z","end":"2026-02-01T18:47:03.601987Z","steps":["trace[1723927378] 'agreement among raft nodes before linearized reading' (duration: 121.356466ms)"],"step_count":1} {"level":"info","ts":"2026-02-01T18:47:03.802356Z","caller":"traceutil/trace.go:171","msg":"trace[1906586648] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"144.485195ms","start":"2026-02-01T18:47:03.657838Z","end":"2026-02-01T18:47:03.802323Z","steps":["trace[1906586648] 'read index received' (duration: 144.320932ms)","trace[1906586648] 'applied index is now lower than readState.Index' (duration: 163.473µs)"],"step_count":2} {"level":"info","ts":"2026-02-01T18:47:03.802464Z","caller":"traceutil/trace.go:171","msg":"trace[1480320623] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"145.36691ms","start":"2026-02-01T18:47:03.657088Z","end":"2026-02-01T18:47:03.802455Z","steps":["trace[1480320623] 'process raft request' (duration: 145.087236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:03.802526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.27622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-01T18:47:03.802577Z","caller":"traceutil/trace.go:171","msg":"trace[1055921317] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:1090; }","duration":"107.376832ms","start":"2026-02-01T18:47:03.695188Z","end":"2026-02-01T18:47:03.802564Z","steps":["trace[1055921317] 'agreement among raft nodes before linearized reading' (duration: 107.289241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:03.802691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.854901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-01T18:47:03.802705Z","caller":"traceutil/trace.go:171","msg":"trace[818108935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"144.887931ms","start":"2026-02-01T18:47:03.657812Z","end":"2026-02-01T18:47:03.8027Z","steps":["trace[818108935] 'agreement among raft nodes before linearized reading' (duration: 144.863611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:04.104604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.108274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-01T18:47:04.104788Z","caller":"traceutil/trace.go:171","msg":"trace[857684630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:1090; }","duration":"214.291186ms","start":"2026-02-01T18:47:03.89044Z","end":"2026-02-01T18:47:04.104731Z","steps":["trace[857684630] 'range keys from in-memory index tree' (duration: 214.017002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:04.105141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.021535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-01T18:47:04.105214Z","caller":"traceutil/trace.go:171","msg":"trace[1930956134] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:1090; }","duration":"182.129716ms","start":"2026-02-01T18:47:03.923067Z","end":"2026-02-01T18:47:04.105197Z","steps":["trace[1930956134] 'range keys from in-memory index tree' (duration: 181.965864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:05.692795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.409641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-01T18:47:05.69287Z","caller":"traceutil/trace.go:171","msg":"trace[1363534460] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:1; response_revision:1095; }","duration":"116.491332ms","start":"2026-02-01T18:47:05.576363Z","end":"2026-02-01T18:47:05.692855Z","steps":["trace[1363534460] 'range keys from in-memory index tree' (duration: 116.343199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:05.693721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.396091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:92 size:68148"} {"level":"info","ts":"2026-02-01T18:47:05.693764Z","caller":"traceutil/trace.go:171","msg":"trace[1074756620] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:92; response_revision:1095; }","duration":"101.464303ms","start":"2026-02-01T18:47:05.592289Z","end":"2026-02-01T18:47:05.693753Z","steps":["trace[1074756620] 'range keys from in-memory index tree' (duration: 101.003786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:06.609062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.981814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-01T18:47:06.609167Z","caller":"traceutil/trace.go:171","msg":"trace[1674744555] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:1096; }","duration":"184.120456ms","start":"2026-02-01T18:47:06.42503Z","end":"2026-02-01T18:47:06.60915Z","steps":["trace[1674744555] 'range keys from in-memory index tree' (duration: 183.732029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-01T18:47:06.609471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.930377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-02-01T18:47:06.609588Z","caller":"traceutil/trace.go:171","msg":"trace[569335786] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1096; }","duration":"160.12674ms","start":"2026-02-01T18:47:06.449442Z","end":"2026-02-01T18:47:06.609569Z","steps":["trace[569335786] 'range keys from in-memory index tree' (duration: 159.738334ms)"],"step_count":1}