{"level":"warn","ts":"2026-01-28T06:20:22.684134Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T06:20:22.684672Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-28T06:20:22.684738Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T06:20:22.684746Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-01-28T06:20:22.684762Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-28T06:20:22.685873Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-01-28T06:20:22.686047Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-28T06:20:22.690843Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.550966ms"} {"level":"info","ts":"2026-01-28T06:20:22.705537Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-01-28T06:20:22.705647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-01-28T06:20:22.70571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-01-28T06:20:22.705743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-28T06:20:22.705784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-01-28T06:20:22.705846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-01-28T06:20:22.714821Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-28T06:20:22.718442Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-28T06:20:22.720509Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-28T06:20:22.722895Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-28T06:20:22.723665Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-28T06:20:22.723912Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-28T06:20:22.724036Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-28T06:20:22.725539Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-28T06:20:22.725844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-01-28T06:20:22.726042Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-01-28T06:20:22.744313Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-28T06:20:22.744562Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-28T06:20:22.744648Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-28T06:20:22.74481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-01-28T06:20:22.744871Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-01-28T06:20:23.406041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-01-28T06:20:23.406094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-28T06:20:23.406124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-01-28T06:20:23.406141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-01-28T06:20:23.406148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-01-28T06:20:23.406159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-01-28T06:20:23.406172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-01-28T06:20:23.408345Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-01-28T06:20:23.415293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T06:20:23.415689Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T06:20:23.41588Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T06:20:23.416656Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-28T06:20:23.416667Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-28T06:20:23.417786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-28T06:20:23.455429Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T06:20:23.455496Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T06:20:23.455513Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T06:20:23.479973Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-01-28T06:20:35.681623Z","caller":"traceutil/trace.go:171","msg":"trace[1897635471] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"333.300269ms","start":"2026-01-28T06:20:35.348299Z","end":"2026-01-28T06:20:35.681599Z","steps":["trace[1897635471] 'process raft request' (duration: 333.172938ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:20:35.682205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:20:35.348286Z","time spent":"333.435781ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4396,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:20:38.611513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.436382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-28T06:20:38.611609Z","caller":"traceutil/trace.go:171","msg":"trace[81515608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"138.601513ms","start":"2026-01-28T06:20:38.472988Z","end":"2026-01-28T06:20:38.611589Z","steps":["trace[81515608] 'range keys from in-memory index tree' (duration: 138.22013ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:20:41.398493Z","caller":"traceutil/trace.go:171","msg":"trace[1117021702] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:466; }","duration":"240.739962ms","start":"2026-01-28T06:20:41.157733Z","end":"2026-01-28T06:20:41.398473Z","steps":["trace[1117021702] 'read index received' (duration: 240.626111ms)","trace[1117021702] 'applied index is now lower than readState.Index' (duration: 112.811µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:20:41.398591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.838973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-28T06:20:41.398614Z","caller":"traceutil/trace.go:171","msg":"trace[1647510213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"240.898644ms","start":"2026-01-28T06:20:41.157707Z","end":"2026-01-28T06:20:41.398606Z","steps":["trace[1647510213] 'agreement among raft nodes before linearized reading' (duration: 240.840243ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:20:41.398711Z","caller":"traceutil/trace.go:171","msg":"trace[988247926] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"241.975163ms","start":"2026-01-28T06:20:41.156729Z","end":"2026-01-28T06:20:41.398704Z","steps":["trace[988247926] 'process raft request' (duration: 241.63628ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:20:41.868657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.113776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:20:41.869798Z","caller":"traceutil/trace.go:171","msg":"trace[1470508451] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"466.300015ms","start":"2026-01-28T06:20:41.403474Z","end":"2026-01-28T06:20:41.869774Z","steps":["trace[1470508451] 'process raft request' (duration: 278.858516ms)","trace[1470508451] 'compare' (duration: 186.011175ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:20:41.870013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:20:41.40345Z","time spent":"466.448646ms","remote":"127.0.0.1:60216","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-01-28T06:20:41.871293Z","caller":"traceutil/trace.go:171","msg":"trace[1249272902] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"304.202654ms","start":"2026-01-28T06:20:41.567076Z","end":"2026-01-28T06:20:41.871279Z","steps":["trace[1249272902] 'read index received' (duration: 115.21254ms)","trace[1249272902] 'applied index is now lower than readState.Index' (duration: 188.988964ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:20:41.872622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.557008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:20:41.872757Z","caller":"traceutil/trace.go:171","msg":"trace[354776616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:455; }","duration":"305.726349ms","start":"2026-01-28T06:20:41.567022Z","end":"2026-01-28T06:20:41.872748Z","steps":["trace[354776616] 'agreement among raft nodes before linearized reading' (duration: 304.385996ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:20:41.872836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:20:41.567002Z","time spent":"305.8257ms","remote":"127.0.0.1:59932","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-01-28T06:20:44.026243Z","caller":"traceutil/trace.go:171","msg":"trace[582056407] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"149.674797ms","start":"2026-01-28T06:20:43.876548Z","end":"2026-01-28T06:20:44.026222Z","steps":["trace[582056407] 'process raft request' (duration: 149.575956ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:20:49.266602Z","caller":"traceutil/trace.go:171","msg":"trace[1353106287] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"316.088021ms","start":"2026-01-28T06:20:48.950492Z","end":"2026-01-28T06:20:49.26658Z","steps":["trace[1353106287] 'read index received' (duration: 238.040771ms)","trace[1353106287] 'applied index is now lower than readState.Index' (duration: 78.04651ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:20:49.266725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.217452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-01-28T06:20:49.26675Z","caller":"traceutil/trace.go:171","msg":"trace[1066811896] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:472; }","duration":"316.279854ms","start":"2026-01-28T06:20:48.95046Z","end":"2026-01-28T06:20:49.26674Z","steps":["trace[1066811896] 'agreement among raft nodes before linearized reading' (duration: 316.187833ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:20:49.266771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:20:48.950444Z","time spent":"316.321554ms","remote":"127.0.0.1:60034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12692,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-01-28T06:20:49.267038Z","caller":"traceutil/trace.go:171","msg":"trace[622455649] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"362.062684ms","start":"2026-01-28T06:20:48.904964Z","end":"2026-01-28T06:20:49.267027Z","steps":["trace[622455649] 'process raft request' (duration: 283.61969ms)","trace[622455649] 'compare' (duration: 77.776828ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:20:49.267914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:20:48.904952Z","time spent":"362.106004ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:20:52.91666Z","caller":"traceutil/trace.go:171","msg":"trace[1753828648] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"249.837589ms","start":"2026-01-28T06:20:52.666797Z","end":"2026-01-28T06:20:52.916635Z","steps":["trace[1753828648] 'process raft request' (duration: 249.673708ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:01.199055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.296222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-28T06:21:01.199125Z","caller":"traceutil/trace.go:171","msg":"trace[1037089782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"228.408834ms","start":"2026-01-28T06:21:00.9707Z","end":"2026-01-28T06:21:01.199109Z","steps":["trace[1037089782] 'range keys from in-memory index tree' (duration: 228.126932ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:01.199156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.577147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ps9fm\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-28T06:21:01.199217Z","caller":"traceutil/trace.go:171","msg":"trace[389908738] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ps9fm; range_end:; response_count:1; response_revision:557; }","duration":"739.667848ms","start":"2026-01-28T06:21:00.459532Z","end":"2026-01-28T06:21:01.1992Z","steps":["trace[389908738] 'range keys from in-memory index tree' (duration: 739.446346ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:01.199245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:00.459518Z","time spent":"739.720918ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-ps9fm\" "} {"level":"warn","ts":"2026-01-28T06:21:01.19949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.72371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:01.199513Z","caller":"traceutil/trace.go:171","msg":"trace[486725134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"633.77226ms","start":"2026-01-28T06:21:00.565734Z","end":"2026-01-28T06:21:01.199506Z","steps":["trace[486725134] 'range keys from in-memory index tree' (duration: 633.674009ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:01.19953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:00.56572Z","time spent":"633.806ms","remote":"127.0.0.1:59932","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-01-28T06:21:01.36213Z","caller":"traceutil/trace.go:171","msg":"trace[190350084] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"149.611016ms","start":"2026-01-28T06:21:01.212499Z","end":"2026-01-28T06:21:01.36211Z","steps":["trace[190350084] 'process raft request' (duration: 144.123671ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:02.067325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T06:21:02.568391Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T06:21:03.068656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690049,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T06:21:03.295566Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.922445656s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-28T06:21:03.46769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.922614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:03.468065Z","caller":"traceutil/trace.go:171","msg":"trace[2128028454] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"2.094969076s","start":"2026-01-28T06:21:01.373057Z","end":"2026-01-28T06:21:03.468026Z","steps":["trace[2128028454] 'process raft request' (duration: 1.922663499s)","trace[2128028454] 'compare' (duration: 171.850253ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:03.468189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:01.373046Z","time spent":"2.095079167s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:21:03.482752Z","caller":"traceutil/trace.go:171","msg":"trace[1957082302] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"1.915946213s","start":"2026-01-28T06:21:01.566774Z","end":"2026-01-28T06:21:03.48272Z","steps":["trace[1957082302] 'read index received' (duration: 1.728956565s)","trace[1957082302] 'applied index is now lower than readState.Index' (duration: 186.988688ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:03.482949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916163395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:03.482967Z","caller":"traceutil/trace.go:171","msg":"trace[1083458457] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.916212945s","start":"2026-01-28T06:21:01.566748Z","end":"2026-01-28T06:21:03.482961Z","steps":["trace[1083458457] 'agreement among raft nodes before linearized reading' (duration: 1.916172314s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:03.482995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:01.566735Z","time spent":"1.916255786s","remote":"127.0.0.1:59932","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-01-28T06:21:03.483177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.914704813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-28T06:21:03.483193Z","caller":"traceutil/trace.go:171","msg":"trace[1125772590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"1.914727973s","start":"2026-01-28T06:21:01.568459Z","end":"2026-01-28T06:21:03.483187Z","steps":["trace[1125772590] 'agreement among raft nodes before linearized reading' (duration: 1.914659272s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:03.483205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:01.568451Z","time spent":"1.914750743s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:03.483336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.283821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-28T06:21:03.483351Z","caller":"traceutil/trace.go:171","msg":"trace[1345636005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"232.298821ms","start":"2026-01-28T06:21:03.251047Z","end":"2026-01-28T06:21:03.483346Z","steps":["trace[1345636005] 'agreement among raft nodes before linearized reading' (duration: 232.22716ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:03.483426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099300083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T06:21:03.483438Z","caller":"traceutil/trace.go:171","msg":"trace[960181669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"1.099334643s","start":"2026-01-28T06:21:02.384099Z","end":"2026-01-28T06:21:03.483434Z","steps":["trace[960181669] 'agreement among raft nodes before linearized reading' (duration: 1.099300272s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:03.48345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:02.384086Z","time spent":"1.099361213s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-28T06:21:04.615496Z","caller":"traceutil/trace.go:171","msg":"trace[354803626] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"125.498626ms","start":"2026-01-28T06:21:04.489981Z","end":"2026-01-28T06:21:04.615479Z","steps":["trace[354803626] 'process raft request' (duration: 125.215144ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:05.921484Z","caller":"traceutil/trace.go:171","msg":"trace[1920356080] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"184.767682ms","start":"2026-01-28T06:21:05.73669Z","end":"2026-01-28T06:21:05.921458Z","steps":["trace[1920356080] 'process raft request' (duration: 184.150197ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:05.92558Z","caller":"traceutil/trace.go:171","msg":"trace[881476409] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"166.09039ms","start":"2026-01-28T06:21:05.75948Z","end":"2026-01-28T06:21:05.925571Z","steps":["trace[881476409] 'read index received' (duration: 161.370151ms)","trace[881476409] 'applied index is now lower than readState.Index' (duration: 4.719679ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:05.925694Z","caller":"traceutil/trace.go:171","msg":"trace[12384986] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"179.123466ms","start":"2026-01-28T06:21:05.746563Z","end":"2026-01-28T06:21:05.925687Z","steps":["trace[12384986] 'process raft request' (duration: 178.959164ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.925816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.322723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.92584Z","caller":"traceutil/trace.go:171","msg":"trace[620977118] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"166.384813ms","start":"2026-01-28T06:21:05.759447Z","end":"2026-01-28T06:21:05.925832Z","steps":["trace[620977118] 'agreement among raft nodes before linearized reading' (duration: 166.318902ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.925958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.431043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.925976Z","caller":"traceutil/trace.go:171","msg":"trace[1972922767] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"166.461743ms","start":"2026-01-28T06:21:05.759509Z","end":"2026-01-28T06:21:05.92597Z","steps":["trace[1972922767] 'agreement among raft nodes before linearized reading' (duration: 166.433423ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.926144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.794683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.92618Z","caller":"traceutil/trace.go:171","msg":"trace[15703363] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"157.829444ms","start":"2026-01-28T06:21:05.768344Z","end":"2026-01-28T06:21:05.926174Z","steps":["trace[15703363] 'agreement among raft nodes before linearized reading' (duration: 157.786193ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.926421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.080496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.926483Z","caller":"traceutil/trace.go:171","msg":"trace[181673936] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"158.149236ms","start":"2026-01-28T06:21:05.768325Z","end":"2026-01-28T06:21:05.926474Z","steps":["trace[181673936] 'agreement among raft nodes before linearized reading' (duration: 158.076665ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.926688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.387268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.926756Z","caller":"traceutil/trace.go:171","msg":"trace[113489455] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"158.476598ms","start":"2026-01-28T06:21:05.768272Z","end":"2026-01-28T06:21:05.926749Z","steps":["trace[113489455] 'agreement among raft nodes before linearized reading' (duration: 158.396958ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.926968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.840738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.927026Z","caller":"traceutil/trace.go:171","msg":"trace[1754174439] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"165.911708ms","start":"2026-01-28T06:21:05.761108Z","end":"2026-01-28T06:21:05.92702Z","steps":["trace[1754174439] 'agreement among raft nodes before linearized reading' (duration: 165.821348ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.927188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.819846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.927241Z","caller":"traceutil/trace.go:171","msg":"trace[1051490919] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"166.873176ms","start":"2026-01-28T06:21:05.760361Z","end":"2026-01-28T06:21:05.927234Z","steps":["trace[1051490919] 'agreement among raft nodes before linearized reading' (duration: 166.810316ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:05.928227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.6047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:05.92825Z","caller":"traceutil/trace.go:171","msg":"trace[660778908] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"168.664121ms","start":"2026-01-28T06:21:05.759578Z","end":"2026-01-28T06:21:05.928243Z","steps":["trace[660778908] 'agreement among raft nodes before linearized reading' (duration: 168.630671ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:15.634341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.956881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-01-28T06:21:15.634389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.879461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-cpc59\" ","response":"range_response_count:1 size:4696"} {"level":"info","ts":"2026-01-28T06:21:15.634499Z","caller":"traceutil/trace.go:171","msg":"trace[884175633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"114.109782ms","start":"2026-01-28T06:21:15.520336Z","end":"2026-01-28T06:21:15.634446Z","steps":["trace[884175633] 'range keys from in-memory index tree' (duration: 113.768539ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:15.634519Z","caller":"traceutil/trace.go:171","msg":"trace[1691582387] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-cpc59; range_end:; response_count:1; response_revision:703; }","duration":"109.034943ms","start":"2026-01-28T06:21:15.525463Z","end":"2026-01-28T06:21:15.634498Z","steps":["trace[1691582387] 'range keys from in-memory index tree' (duration: 108.758611ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:15.635405Z","caller":"traceutil/trace.go:171","msg":"trace[890657842] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"103.247947ms","start":"2026-01-28T06:21:15.532121Z","end":"2026-01-28T06:21:15.635369Z","steps":["trace[890657842] 'process raft request' (duration: 103.063706ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:23.895687Z","caller":"traceutil/trace.go:171","msg":"trace[1990137363] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"195.305117ms","start":"2026-01-28T06:21:23.700364Z","end":"2026-01-28T06:21:23.895669Z","steps":["trace[1990137363] 'process raft request' (duration: 195.208333ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:23.89599Z","caller":"traceutil/trace.go:171","msg":"trace[1204883778] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:793; }","duration":"132.271267ms","start":"2026-01-28T06:21:23.763713Z","end":"2026-01-28T06:21:23.895984Z","steps":["trace[1204883778] 'read index received' (duration: 132.269077ms)","trace[1204883778] 'applied index is now lower than readState.Index' (duration: 1.85µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:23.896084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.330811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T06:21:23.8961Z","caller":"traceutil/trace.go:171","msg":"trace[934123743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"109.372573ms","start":"2026-01-28T06:21:23.786722Z","end":"2026-01-28T06:21:23.896094Z","steps":["trace[934123743] 'agreement among raft nodes before linearized reading' (duration: 109.29456ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:23.896204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.496998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T06:21:23.896218Z","caller":"traceutil/trace.go:171","msg":"trace[254906993] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:770; }","duration":"132.53462ms","start":"2026-01-28T06:21:23.763679Z","end":"2026-01-28T06:21:23.896214Z","steps":["trace[254906993] 'agreement among raft nodes before linearized reading' (duration: 132.496328ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.065669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690485,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-28T06:21:30.342987Z","caller":"traceutil/trace.go:171","msg":"trace[1118621236] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"874.010682ms","start":"2026-01-28T06:21:29.468958Z","end":"2026-01-28T06:21:30.342969Z","steps":["trace[1118621236] 'process raft request' (duration: 873.923349ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.343085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:29.468941Z","time spent":"874.089425ms","remote":"127.0.0.1:60126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:21:30.345217Z","caller":"traceutil/trace.go:171","msg":"trace[561207194] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"780.108055ms","start":"2026-01-28T06:21:29.56509Z","end":"2026-01-28T06:21:30.345198Z","steps":["trace[561207194] 'read index received' (duration: 778.275909ms)","trace[561207194] 'applied index is now lower than readState.Index' (duration: 1.831476ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:30.345433Z","caller":"traceutil/trace.go:171","msg":"trace[782265519] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"704.630265ms","start":"2026-01-28T06:21:29.640794Z","end":"2026-01-28T06:21:30.345424Z","steps":["trace[782265519] 'process raft request' (duration: 704.311212ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.345511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:29.640771Z","time spent":"704.681448ms","remote":"127.0.0.1:60216","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-01-28T06:21:30.345658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.583425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:30.345688Z","caller":"traceutil/trace.go:171","msg":"trace[1884633602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"780.647258ms","start":"2026-01-28T06:21:29.56503Z","end":"2026-01-28T06:21:30.345677Z","steps":["trace[1884633602] 'agreement among raft nodes before linearized reading' (duration: 780.600406ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.345706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:29.56501Z","time spent":"780.6922ms","remote":"127.0.0.1:59932","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-01-28T06:21:30.345817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.631247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-28T06:21:30.345851Z","caller":"traceutil/trace.go:171","msg":"trace[1003573780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"427.7014ms","start":"2026-01-28T06:21:29.918143Z","end":"2026-01-28T06:21:30.345844Z","steps":["trace[1003573780] 'agreement among raft nodes before linearized reading' (duration: 427.596105ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.345871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:29.918121Z","time spent":"427.746192ms","remote":"127.0.0.1:60216","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":"warn","ts":"2026-01-28T06:21:30.34588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.368441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T06:21:30.345897Z","caller":"traceutil/trace.go:171","msg":"trace[505443417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"415.403932ms","start":"2026-01-28T06:21:29.930488Z","end":"2026-01-28T06:21:30.345892Z","steps":["trace[505443417] 'agreement among raft nodes before linearized reading' (duration: 415.343099ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.345913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:29.930478Z","time spent":"415.432214ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:30.345971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.910799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-28T06:21:30.345982Z","caller":"traceutil/trace.go:171","msg":"trace[466808190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"336.9436ms","start":"2026-01-28T06:21:30.009035Z","end":"2026-01-28T06:21:30.345978Z","steps":["trace[466808190] 'agreement among raft nodes before linearized reading' (duration: 336.910319ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.345993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:30.009022Z","time spent":"336.968911ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:30.346028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.854304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-28T06:21:30.346049Z","caller":"traceutil/trace.go:171","msg":"trace[2118014020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"421.894425ms","start":"2026-01-28T06:21:29.924149Z","end":"2026-01-28T06:21:30.346043Z","steps":["trace[2118014020] 'agreement among raft nodes before linearized reading' (duration: 421.840973ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.346065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:29.92414Z","time spent":"421.921777ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:30.346157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.586381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T06:21:30.346172Z","caller":"traceutil/trace.go:171","msg":"trace[1475435208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:793; }","duration":"425.621412ms","start":"2026-01-28T06:21:29.920546Z","end":"2026-01-28T06:21:30.346167Z","steps":["trace[1475435208] 'agreement among raft nodes before linearized reading' (duration: 425.56819ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:30.346195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:29.920536Z","time spent":"425.654914ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-28T06:21:38.815707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.45372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:38.81577Z","caller":"traceutil/trace.go:171","msg":"trace[1165550643] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"328.494052ms","start":"2026-01-28T06:21:38.487265Z","end":"2026-01-28T06:21:38.815759Z","steps":["trace[1165550643] 'read index received' (duration: 22.574289ms)","trace[1165550643] 'applied index is now lower than readState.Index' (duration: 305.919163ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:38.815895Z","caller":"traceutil/trace.go:171","msg":"trace[1699268182] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"348.303415ms","start":"2026-01-28T06:21:38.467584Z","end":"2026-01-28T06:21:38.815888Z","steps":["trace[1699268182] 'process raft request' (duration: 171.619986ms)","trace[1699268182] 'compare' (duration: 176.341386ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:38.815929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:38.467577Z","time spent":"348.333846ms","remote":"127.0.0.1:60216","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-01-28T06:21:38.816069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.804673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-28T06:21:38.816084Z","caller":"traceutil/trace.go:171","msg":"trace[1495936687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"328.853995ms","start":"2026-01-28T06:21:38.487225Z","end":"2026-01-28T06:21:38.816079Z","steps":["trace[1495936687] 'agreement among raft nodes before linearized reading' (duration: 328.791803ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:38.816099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:38.487196Z","time spent":"328.898667ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:38.816181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.087556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T06:21:38.816195Z","caller":"traceutil/trace.go:171","msg":"trace[580485791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"328.144038ms","start":"2026-01-28T06:21:38.488046Z","end":"2026-01-28T06:21:38.81619Z","steps":["trace[580485791] 'agreement among raft nodes before linearized reading' (duration: 328.109497ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:38.81621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:38.488029Z","time spent":"328.17904ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:38.816307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.297936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:38.81632Z","caller":"traceutil/trace.go:171","msg":"trace[1958855250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"251.338107ms","start":"2026-01-28T06:21:38.564978Z","end":"2026-01-28T06:21:38.816316Z","steps":["trace[1958855250] 'agreement among raft nodes before linearized reading' (duration: 251.316906ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:39.420514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690570,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-28T06:21:39.57323Z","caller":"traceutil/trace.go:171","msg":"trace[1212010642] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"653.663037ms","start":"2026-01-28T06:21:38.919549Z","end":"2026-01-28T06:21:39.573212Z","steps":["trace[1212010642] 'read index received' (duration: 653.550553ms)","trace[1212010642] 'applied index is now lower than readState.Index' (duration: 111.844µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:39.573294Z","caller":"traceutil/trace.go:171","msg":"trace[1121339131] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"750.656696ms","start":"2026-01-28T06:21:38.82263Z","end":"2026-01-28T06:21:39.573286Z","steps":["trace[1121339131] 'process raft request' (duration: 750.50543ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:39.573351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:38.822611Z","time spent":"750.695907ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:21:39.57351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.950008ms","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-01-28T06:21:39.573561Z","caller":"traceutil/trace.go:171","msg":"trace[865768467] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:820; }","duration":"654.044971ms","start":"2026-01-28T06:21:38.919503Z","end":"2026-01-28T06:21:39.573548Z","steps":["trace[865768467] 'agreement among raft nodes before linearized reading' (duration: 653.969179ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:39.573586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:38.919483Z","time spent":"654.096004ms","remote":"127.0.0.1:60268","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":"info","ts":"2026-01-28T06:21:40.815778Z","caller":"traceutil/trace.go:171","msg":"trace[1209705983] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"112.017186ms","start":"2026-01-28T06:21:40.703734Z","end":"2026-01-28T06:21:40.815752Z","steps":["trace[1209705983] 'process raft request' (duration: 111.85525ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:40.969099Z","caller":"traceutil/trace.go:171","msg":"trace[1607136351] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"144.805666ms","start":"2026-01-28T06:21:40.824274Z","end":"2026-01-28T06:21:40.969079Z","steps":["trace[1607136351] 'process raft request' (duration: 71.977627ms)","trace[1607136351] 'compare' (duration: 72.509667ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:41.754306Z","caller":"traceutil/trace.go:171","msg":"trace[607702009] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"175.07318ms","start":"2026-01-28T06:21:41.579193Z","end":"2026-01-28T06:21:41.754266Z","steps":["trace[607702009] 'process raft request' (duration: 174.933595ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:42.070281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.151835ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-28T06:21:42.68828Z","caller":"traceutil/trace.go:171","msg":"trace[236373623] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"116.418226ms","start":"2026-01-28T06:21:42.571839Z","end":"2026-01-28T06:21:42.688257Z","steps":["trace[236373623] 'process raft request' (duration: 116.171627ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:43.172274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.874002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:43.172383Z","caller":"traceutil/trace.go:171","msg":"trace[166703299] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:855; }","duration":"454.928916ms","start":"2026-01-28T06:21:42.717433Z","end":"2026-01-28T06:21:43.172362Z","steps":["trace[166703299] 'read index received' (duration: 123.808345ms)","trace[166703299] 'applied index is now lower than readState.Index' (duration: 331.119341ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:43.172558Z","caller":"traceutil/trace.go:171","msg":"trace[359799953] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"480.778686ms","start":"2026-01-28T06:21:42.691764Z","end":"2026-01-28T06:21:43.172542Z","steps":["trace[359799953] 'process raft request' (duration: 149.543991ms)","trace[359799953] 'compare' (duration: 330.626653ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:43.172783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:42.691753Z","time spent":"480.965922ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:21:43.17319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.734184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:43.173965Z","caller":"traceutil/trace.go:171","msg":"trace[1450488852] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:828; }","duration":"456.561994ms","start":"2026-01-28T06:21:42.717378Z","end":"2026-01-28T06:21:43.17394Z","steps":["trace[1450488852] 'agreement among raft nodes before linearized reading' (duration: 455.715774ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:43.174279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:42.717358Z","time spent":"456.895537ms","remote":"127.0.0.1:60248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:21:43.173384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.01936ms","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-01-28T06:21:43.173574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.223633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-28T06:21:43.173645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.057671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T06:21:43.175923Z","caller":"traceutil/trace.go:171","msg":"trace[1968507354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"157.282581ms","start":"2026-01-28T06:21:43.018553Z","end":"2026-01-28T06:21:43.175835Z","steps":["trace[1968507354] 'agreement among raft nodes before linearized reading' (duration: 155.04017ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:43.176071Z","caller":"traceutil/trace.go:171","msg":"trace[607245896] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"202.720037ms","start":"2026-01-28T06:21:42.973303Z","end":"2026-01-28T06:21:43.176023Z","steps":["trace[607245896] 'agreement among raft nodes before linearized reading' (duration: 199.899886ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:43.176401Z","caller":"traceutil/trace.go:171","msg":"trace[248711156] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:828; }","duration":"134.087086ms","start":"2026-01-28T06:21:43.042303Z","end":"2026-01-28T06:21:43.17639Z","steps":["trace[248711156] 'agreement among raft nodes before linearized reading' (duration: 131.247813ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:43.553357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.618382ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:43.553469Z","caller":"traceutil/trace.go:171","msg":"trace[432443480] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:829; }","duration":"172.735336ms","start":"2026-01-28T06:21:43.380715Z","end":"2026-01-28T06:21:43.55345Z","steps":["trace[432443480] 'range keys from in-memory index tree' (duration: 172.57047ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:43.554349Z","caller":"traceutil/trace.go:171","msg":"trace[314978694] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"197.843ms","start":"2026-01-28T06:21:43.356493Z","end":"2026-01-28T06:21:43.554336Z","steps":["trace[314978694] 'read index received' (duration: 123.398641ms)","trace[314978694] 'applied index is now lower than readState.Index' (duration: 74.443579ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:43.554701Z","caller":"traceutil/trace.go:171","msg":"trace[282914284] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"373.299865ms","start":"2026-01-28T06:21:43.181387Z","end":"2026-01-28T06:21:43.554686Z","steps":["trace[282914284] 'process raft request' (duration: 298.497893ms)","trace[282914284] 'compare' (duration: 74.19994ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:43.55488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:43.181382Z","time spent":"373.359187ms","remote":"127.0.0.1:60216","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-01-28T06:21:43.555216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.720321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:21:43.555259Z","caller":"traceutil/trace.go:171","msg":"trace[1272835839] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:830; }","duration":"198.765083ms","start":"2026-01-28T06:21:43.356483Z","end":"2026-01-28T06:21:43.555248Z","steps":["trace[1272835839] 'agreement among raft nodes before linearized reading' (duration: 198.67455ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:43.915529Z","caller":"traceutil/trace.go:171","msg":"trace[1014095697] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"152.093003ms","start":"2026-01-28T06:21:43.763408Z","end":"2026-01-28T06:21:43.915501Z","steps":["trace[1014095697] 'process raft request' (duration: 151.963869ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:45.059159Z","caller":"traceutil/trace.go:171","msg":"trace[1163684050] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:859; }","duration":"111.337963ms","start":"2026-01-28T06:21:44.947796Z","end":"2026-01-28T06:21:45.059134Z","steps":["trace[1163684050] 'read index received' (duration: 111.154626ms)","trace[1163684050] 'applied index is now lower than readState.Index' (duration: 182.337µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:45.059278Z","caller":"traceutil/trace.go:171","msg":"trace[1420755634] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"359.701824ms","start":"2026-01-28T06:21:44.699566Z","end":"2026-01-28T06:21:45.059267Z","steps":["trace[1420755634] 'process raft request' (duration: 359.405064ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:45.059411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:44.699542Z","time spent":"359.790028ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:21:45.059586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.773178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:45.059642Z","caller":"traceutil/trace.go:171","msg":"trace[1580074405] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:832; }","duration":"111.865871ms","start":"2026-01-28T06:21:44.947763Z","end":"2026-01-28T06:21:45.059629Z","steps":["trace[1580074405] 'agreement among raft nodes before linearized reading' (duration: 111.785078ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:45.313068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.825433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-28T06:21:45.31312Z","caller":"traceutil/trace.go:171","msg":"trace[969149474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"135.927787ms","start":"2026-01-28T06:21:45.17718Z","end":"2026-01-28T06:21:45.313107Z","steps":["trace[969149474] 'range keys from in-memory index tree' (duration: 135.70399ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:45.42601Z","caller":"traceutil/trace.go:171","msg":"trace[1996669212] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"108.662547ms","start":"2026-01-28T06:21:45.317305Z","end":"2026-01-28T06:21:45.425967Z","steps":["trace[1996669212] 'process raft request' (duration: 108.533623ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:45.742367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.388645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:45.742445Z","caller":"traceutil/trace.go:171","msg":"trace[467587778] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"236.870402ms","start":"2026-01-28T06:21:45.505559Z","end":"2026-01-28T06:21:45.742429Z","steps":["trace[467587778] 'read index received' (duration: 67.29279ms)","trace[467587778] 'applied index is now lower than readState.Index' (duration: 169.576512ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:45.742633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.051929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:21:45.742686Z","caller":"traceutil/trace.go:171","msg":"trace[800012606] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:834; }","duration":"237.156422ms","start":"2026-01-28T06:21:45.505515Z","end":"2026-01-28T06:21:45.742671Z","steps":["trace[800012606] 'agreement among raft nodes before linearized reading' (duration: 237.048598ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:45.742711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.019165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-28T06:21:45.742737Z","caller":"traceutil/trace.go:171","msg":"trace[2007666882] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:834; }","duration":"183.045067ms","start":"2026-01-28T06:21:45.559682Z","end":"2026-01-28T06:21:45.742728Z","steps":["trace[2007666882] 'agreement among raft nodes before linearized reading' (duration: 182.946563ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:45.742831Z","caller":"traceutil/trace.go:171","msg":"trace[1737594611] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"424.77468ms","start":"2026-01-28T06:21:45.318046Z","end":"2026-01-28T06:21:45.742821Z","steps":["trace[1737594611] 'process raft request' (duration: 254.857696ms)","trace[1737594611] 'compare' (duration: 169.22572ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:45.742889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:45.31803Z","time spent":"424.819061ms","remote":"127.0.0.1:60216","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-01-28T06:21:46.220903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.107418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:46.220967Z","caller":"traceutil/trace.go:171","msg":"trace[2048695784] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"302.351116ms","start":"2026-01-28T06:21:45.918604Z","end":"2026-01-28T06:21:46.220955Z","steps":["trace[2048695784] 'read index received' (duration: 23.981µs)","trace[2048695784] 'applied index is now lower than readState.Index' (duration: 302.326415ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:46.221058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.45352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-28T06:21:46.221071Z","caller":"traceutil/trace.go:171","msg":"trace[1790636533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:835; }","duration":"302.487531ms","start":"2026-01-28T06:21:45.918579Z","end":"2026-01-28T06:21:46.221066Z","steps":["trace[1790636533] 'agreement among raft nodes before linearized reading' (duration: 302.403428ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:46.221087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:45.918566Z","time spent":"302.516952ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-28T06:21:46.221214Z","caller":"traceutil/trace.go:171","msg":"trace[346423656] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"473.96426ms","start":"2026-01-28T06:21:45.747242Z","end":"2026-01-28T06:21:46.221206Z","steps":["trace[346423656] 'process raft request' (duration: 123.494259ms)","trace[346423656] 'compare' (duration: 350.039776ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:46.22124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:45.747226Z","time spent":"474.000251ms","remote":"127.0.0.1:60216","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-01-28T06:21:46.884262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T06:21:47.035569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.998764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:47.035636Z","caller":"traceutil/trace.go:171","msg":"trace[170780448] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"810.046852ms","start":"2026-01-28T06:21:46.225575Z","end":"2026-01-28T06:21:47.035621Z","steps":["trace[170780448] 'process raft request' (duration: 151.941344ms)","trace[170780448] 'compare' (duration: 657.773777ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:47.035666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:46.225558Z","time spent":"810.094873ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:21:47.037389Z","caller":"traceutil/trace.go:171","msg":"trace[944095899] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"654.75059ms","start":"2026-01-28T06:21:46.382612Z","end":"2026-01-28T06:21:47.037363Z","steps":["trace[944095899] 'read index received' (duration: 654.619466ms)","trace[944095899] 'applied index is now lower than readState.Index' (duration: 130.204µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:47.037601Z","caller":"traceutil/trace.go:171","msg":"trace[292292643] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"666.78535ms","start":"2026-01-28T06:21:46.370804Z","end":"2026-01-28T06:21:47.037589Z","steps":["trace[292292643] 'process raft request' (duration: 666.483429ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:47.037696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:46.370787Z","time spent":"666.841641ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:21:47.038118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.493596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-28T06:21:47.038231Z","caller":"traceutil/trace.go:171","msg":"trace[1676626588] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:837; }","duration":"655.6238ms","start":"2026-01-28T06:21:46.38259Z","end":"2026-01-28T06:21:47.038214Z","steps":["trace[1676626588] 'agreement among raft nodes before linearized reading' (duration: 655.244717ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:47.038333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:46.382576Z","time spent":"655.743224ms","remote":"127.0.0.1:59970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-01-28T06:21:47.038742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.021168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:47.03887Z","caller":"traceutil/trace.go:171","msg":"trace[1488249215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:837; }","duration":"471.169284ms","start":"2026-01-28T06:21:46.567688Z","end":"2026-01-28T06:21:47.038857Z","steps":["trace[1488249215] 'agreement among raft nodes before linearized reading' (duration: 471.014838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:47.038997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:46.567642Z","time spent":"471.343779ms","remote":"127.0.0.1:59932","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-01-28T06:21:47.331288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.909472ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-01-28T06:21:47.331406Z","caller":"traceutil/trace.go:171","msg":"trace[394294539] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"291.016551ms","start":"2026-01-28T06:21:47.040372Z","end":"2026-01-28T06:21:47.331388Z","steps":["trace[394294539] 'read index received' (duration: 133.015365ms)","trace[394294539] 'applied index is now lower than readState.Index' (duration: 157.999665ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:47.331678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.321831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:47.331709Z","caller":"traceutil/trace.go:171","msg":"trace[36639408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:837; }","duration":"291.368773ms","start":"2026-01-28T06:21:47.040331Z","end":"2026-01-28T06:21:47.3317Z","steps":["trace[36639408] 'agreement among raft nodes before linearized reading' (duration: 291.309611ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:47.331927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.111156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T06:21:47.331955Z","caller":"traceutil/trace.go:171","msg":"trace[455242923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:837; }","duration":"269.152627ms","start":"2026-01-28T06:21:47.062793Z","end":"2026-01-28T06:21:47.331946Z","steps":["trace[455242923] 'agreement among raft nodes before linearized reading' (duration: 269.044473ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:47.679252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.262784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:47.679404Z","caller":"traceutil/trace.go:171","msg":"trace[690306179] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"346.67023ms","start":"2026-01-28T06:21:47.332711Z","end":"2026-01-28T06:21:47.679382Z","steps":["trace[690306179] 'process raft request' (duration: 146.196019ms)","trace[690306179] 'compare' (duration: 200.1399ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:47.679489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:47.332695Z","time spent":"346.765783ms","remote":"127.0.0.1:59970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:21:47.73325Z","caller":"traceutil/trace.go:171","msg":"trace[991871454] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:866; }","duration":"303.792512ms","start":"2026-01-28T06:21:47.429439Z","end":"2026-01-28T06:21:47.733231Z","steps":["trace[991871454] 'read index received' (duration: 49.481126ms)","trace[991871454] 'applied index is now lower than readState.Index' (duration: 254.310846ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:47.733338Z","caller":"traceutil/trace.go:171","msg":"trace[1422350471] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"398.779086ms","start":"2026-01-28T06:21:47.334553Z","end":"2026-01-28T06:21:47.733332Z","steps":["trace[1422350471] 'process raft request' (duration: 398.582369ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:47.733408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:47.33454Z","time spent":"398.814027ms","remote":"127.0.0.1:60216","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-01-28T06:21:47.736276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.838067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-28T06:21:47.736302Z","caller":"traceutil/trace.go:171","msg":"trace[1859513420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:839; }","duration":"306.889179ms","start":"2026-01-28T06:21:47.429406Z","end":"2026-01-28T06:21:47.736295Z","steps":["trace[1859513420] 'agreement among raft nodes before linearized reading' (duration: 304.078402ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:47.736318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:47.429394Z","time spent":"306.91966ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:47.989856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.386309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:47.989925Z","caller":"traceutil/trace.go:171","msg":"trace[654374640] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:868; }","duration":"243.362989ms","start":"2026-01-28T06:21:47.746546Z","end":"2026-01-28T06:21:47.989909Z","steps":["trace[654374640] 'read index received' (duration: 44.831726ms)","trace[654374640] 'applied index is now lower than readState.Index' (duration: 198.530513ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:47.990008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.461823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-28T06:21:47.990023Z","caller":"traceutil/trace.go:171","msg":"trace[1090238819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:840; }","duration":"243.477893ms","start":"2026-01-28T06:21:47.746539Z","end":"2026-01-28T06:21:47.990017Z","steps":["trace[1090238819] 'agreement among raft nodes before linearized reading' (duration: 243.401391ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:47.989997Z","caller":"traceutil/trace.go:171","msg":"trace[285172263] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"250.969371ms","start":"2026-01-28T06:21:47.738999Z","end":"2026-01-28T06:21:47.989968Z","steps":["trace[285172263] 'process raft request' (duration: 52.402507ms)","trace[285172263] 'compare' (duration: 198.315086ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:48.431017Z","caller":"traceutil/trace.go:171","msg":"trace[1470504669] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"125.370706ms","start":"2026-01-28T06:21:48.305629Z","end":"2026-01-28T06:21:48.431Z","steps":["trace[1470504669] 'process raft request' (duration: 125.280363ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:48.670248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.886131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:48.670311Z","caller":"traceutil/trace.go:171","msg":"trace[1245879004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"105.988855ms","start":"2026-01-28T06:21:48.564307Z","end":"2026-01-28T06:21:48.670296Z","steps":["trace[1245879004] 'range keys from in-memory index tree' (duration: 105.831119ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:49.25062Z","caller":"traceutil/trace.go:171","msg":"trace[2073269899] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"205.776604ms","start":"2026-01-28T06:21:49.044816Z","end":"2026-01-28T06:21:49.250593Z","steps":["trace[2073269899] 'process raft request' (duration: 205.636969ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:50.131634Z","caller":"traceutil/trace.go:171","msg":"trace[1657043141] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"132.448855ms","start":"2026-01-28T06:21:49.999161Z","end":"2026-01-28T06:21:50.131609Z","steps":["trace[1657043141] 'process raft request' (duration: 131.879386ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:50.314628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.09318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:50.314709Z","caller":"traceutil/trace.go:171","msg":"trace[181622291] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"179.099311ms","start":"2026-01-28T06:21:50.13559Z","end":"2026-01-28T06:21:50.314689Z","steps":["trace[181622291] 'process raft request' (duration: 70.884887ms)","trace[181622291] 'compare' (duration: 108.021078ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:50.589454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.451818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-28T06:21:50.589523Z","caller":"traceutil/trace.go:171","msg":"trace[1806858614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:846; }","duration":"154.550481ms","start":"2026-01-28T06:21:50.434954Z","end":"2026-01-28T06:21:50.589505Z","steps":["trace[1806858614] 'range keys from in-memory index tree' (duration: 154.330734ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:50.693453Z","caller":"traceutil/trace.go:171","msg":"trace[2009848341] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"100.710994ms","start":"2026-01-28T06:21:50.592718Z","end":"2026-01-28T06:21:50.69343Z","steps":["trace[2009848341] 'process raft request' (duration: 100.571759ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:51.101254Z","caller":"traceutil/trace.go:171","msg":"trace[509573616] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"167.629853ms","start":"2026-01-28T06:21:50.933599Z","end":"2026-01-28T06:21:51.101228Z","steps":["trace[509573616] 'process raft request' (duration: 167.468248ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:51.729415Z","caller":"traceutil/trace.go:171","msg":"trace[1528531726] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"164.977851ms","start":"2026-01-28T06:21:51.564413Z","end":"2026-01-28T06:21:51.729391Z","steps":["trace[1528531726] 'read index received' (duration: 164.805796ms)","trace[1528531726] 'applied index is now lower than readState.Index' (duration: 171.245µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:51.729547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.110746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:51.729623Z","caller":"traceutil/trace.go:171","msg":"trace[1413740863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:849; }","duration":"165.193448ms","start":"2026-01-28T06:21:51.564374Z","end":"2026-01-28T06:21:51.729567Z","steps":["trace[1413740863] 'agreement among raft nodes before linearized reading' (duration: 165.102045ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:51.730046Z","caller":"traceutil/trace.go:171","msg":"trace[1779372602] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"441.720627ms","start":"2026-01-28T06:21:51.288309Z","end":"2026-01-28T06:21:51.730029Z","steps":["trace[1779372602] 'process raft request' (duration: 440.968372ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:51.730173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:51.288292Z","time spent":"441.785949ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:21:51.89793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.525566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T06:21:51.898005Z","caller":"traceutil/trace.go:171","msg":"trace[899612060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:849; }","duration":"108.63211ms","start":"2026-01-28T06:21:51.789351Z","end":"2026-01-28T06:21:51.897983Z","steps":["trace[899612060] 'range keys from in-memory index tree' (duration: 108.394012ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:52.097226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.120311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-28T06:21:52.097545Z","caller":"traceutil/trace.go:171","msg":"trace[1069525242] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"196.891133ms","start":"2026-01-28T06:21:51.90064Z","end":"2026-01-28T06:21:52.097531Z","steps":["trace[1069525242] 'process raft request' (duration: 196.78208ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:52.397082Z","caller":"traceutil/trace.go:171","msg":"trace[1334355024] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"257.442089ms","start":"2026-01-28T06:21:52.139622Z","end":"2026-01-28T06:21:52.397064Z","steps":["trace[1334355024] 'process raft request' (duration: 195.06663ms)","trace[1334355024] 'compare' (duration: 61.921755ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:52.655442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.994709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:52.656385Z","caller":"traceutil/trace.go:171","msg":"trace[337932981] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"255.402632ms","start":"2026-01-28T06:21:52.400961Z","end":"2026-01-28T06:21:52.656363Z","steps":["trace[337932981] 'process raft request' (duration: 121.41151ms)","trace[337932981] 'compare' (duration: 132.721101ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:52.957227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.110751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:52.957331Z","caller":"traceutil/trace.go:171","msg":"trace[1719021366] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"256.54738ms","start":"2026-01-28T06:21:52.700759Z","end":"2026-01-28T06:21:52.957307Z","steps":["trace[1719021366] 'process raft request' (duration: 120.285214ms)","trace[1719021366] 'compare' (duration: 136.005908ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:54.27297Z","caller":"traceutil/trace.go:171","msg":"trace[1510440400] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"169.447378ms","start":"2026-01-28T06:21:54.103498Z","end":"2026-01-28T06:21:54.272945Z","steps":["trace[1510440400] 'process raft request' (duration: 169.340834ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:55.267714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.396538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-28T06:21:55.26808Z","caller":"traceutil/trace.go:171","msg":"trace[507094320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:857; }","duration":"306.808901ms","start":"2026-01-28T06:21:54.961254Z","end":"2026-01-28T06:21:55.268063Z","steps":["trace[507094320] 'range keys from in-memory index tree' (duration: 306.254094ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:55.268185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:54.961235Z","time spent":"306.940006ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T06:21:55.268469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.664394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:55.268536Z","caller":"traceutil/trace.go:171","msg":"trace[389723508] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:857; }","duration":"201.793678ms","start":"2026-01-28T06:21:55.06672Z","end":"2026-01-28T06:21:55.268514Z","steps":["trace[389723508] 'count revisions from in-memory index tree' (duration: 201.53423ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:21:55.267795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.905693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4911"} {"level":"info","ts":"2026-01-28T06:21:55.270133Z","caller":"traceutil/trace.go:171","msg":"trace[1099214738] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:857; }","duration":"105.354311ms","start":"2026-01-28T06:21:55.164768Z","end":"2026-01-28T06:21:55.270122Z","steps":["trace[1099214738] 'range keys from in-memory index tree' (duration: 102.710787ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:57.559512Z","caller":"traceutil/trace.go:171","msg":"trace[978301437] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"247.21951ms","start":"2026-01-28T06:21:57.312263Z","end":"2026-01-28T06:21:57.559483Z","steps":["trace[978301437] 'process raft request' (duration: 155.400948ms)","trace[978301437] 'compare' (duration: 91.712139ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:21:58.607287Z","caller":"traceutil/trace.go:171","msg":"trace[1182193683] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"294.756186ms","start":"2026-01-28T06:21:58.31251Z","end":"2026-01-28T06:21:58.607266Z","steps":["trace[1182193683] 'process raft request' (duration: 294.450777ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:58.741695Z","caller":"traceutil/trace.go:171","msg":"trace[2053784737] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"130.895276ms","start":"2026-01-28T06:21:58.61078Z","end":"2026-01-28T06:21:58.741675Z","steps":["trace[2053784737] 'process raft request' (duration: 60.839691ms)","trace[2053784737] 'compare' (duration: 69.987473ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:59.342035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.591474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:21:59.342103Z","caller":"traceutil/trace.go:171","msg":"trace[1860031985] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"534.335011ms","start":"2026-01-28T06:21:58.807753Z","end":"2026-01-28T06:21:59.342088Z","steps":["trace[1860031985] 'process raft request' (duration: 71.635204ms)","trace[1860031985] 'compare' (duration: 462.368667ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:59.342133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:58.807738Z","time spent":"534.381962ms","remote":"127.0.0.1:60216","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-01-28T06:21:59.673527Z","caller":"traceutil/trace.go:171","msg":"trace[381319855] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"106.441732ms","start":"2026-01-28T06:21:59.567062Z","end":"2026-01-28T06:21:59.673504Z","steps":["trace[381319855] 'read index received' (duration: 52.681135ms)","trace[381319855] 'applied index is now lower than readState.Index' (duration: 53.759287ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:59.673703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.582776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:21:59.673744Z","caller":"traceutil/trace.go:171","msg":"trace[305634729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:870; }","duration":"106.70569ms","start":"2026-01-28T06:21:59.567027Z","end":"2026-01-28T06:21:59.673732Z","steps":["trace[305634729] 'agreement among raft nodes before linearized reading' (duration: 106.559425ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:21:59.674003Z","caller":"traceutil/trace.go:171","msg":"trace[1320745006] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"300.111648ms","start":"2026-01-28T06:21:59.373876Z","end":"2026-01-28T06:21:59.673987Z","steps":["trace[1320745006] 'process raft request' (duration: 245.902508ms)","trace[1320745006] 'compare' (duration: 53.565391ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:21:59.674093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:21:59.373867Z","time spent":"300.17112ms","remote":"127.0.0.1:60216","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-01-28T06:22:01.010056Z","caller":"traceutil/trace.go:171","msg":"trace[678405272] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"181.776619ms","start":"2026-01-28T06:22:00.828262Z","end":"2026-01-28T06:22:01.010038Z","steps":["trace[678405272] 'process raft request' (duration: 181.686466ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:02.493127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.685546ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-28T06:22:03.135958Z","caller":"traceutil/trace.go:171","msg":"trace[1349925194] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"104.389865ms","start":"2026-01-28T06:22:03.031545Z","end":"2026-01-28T06:22:03.135935Z","steps":["trace[1349925194] 'read index received' (duration: 104.22957ms)","trace[1349925194] 'applied index is now lower than readState.Index' (duration: 159.355µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:03.136066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.502749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:03.136096Z","caller":"traceutil/trace.go:171","msg":"trace[1829841244] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:879; }","duration":"104.57144ms","start":"2026-01-28T06:22:03.031516Z","end":"2026-01-28T06:22:03.136087Z","steps":["trace[1829841244] 'agreement among raft nodes before linearized reading' (duration: 104.502858ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:03.136211Z","caller":"traceutil/trace.go:171","msg":"trace[1701505142] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"120.204818ms","start":"2026-01-28T06:22:03.015983Z","end":"2026-01-28T06:22:03.136188Z","steps":["trace[1701505142] 'process raft request' (duration: 119.813287ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:05.674802Z","caller":"traceutil/trace.go:171","msg":"trace[440839936] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"106.57163ms","start":"2026-01-28T06:22:05.568214Z","end":"2026-01-28T06:22:05.674786Z","steps":["trace[440839936] 'read index received' (duration: 106.482017ms)","trace[440839936] 'applied index is now lower than readState.Index' (duration: 88.833µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:05.674887Z","caller":"traceutil/trace.go:171","msg":"trace[604596597] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"156.158303ms","start":"2026-01-28T06:22:05.518723Z","end":"2026-01-28T06:22:05.674881Z","steps":["trace[604596597] 'process raft request' (duration: 155.957168ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:05.674992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.766115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:05.675008Z","caller":"traceutil/trace.go:171","msg":"trace[971579453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"106.820867ms","start":"2026-01-28T06:22:05.568182Z","end":"2026-01-28T06:22:05.675003Z","steps":["trace[971579453] 'agreement among raft nodes before linearized reading' (duration: 106.782886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:06.413258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.315215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-01-28T06:22:06.413312Z","caller":"traceutil/trace.go:171","msg":"trace[1064227989] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:887; }","duration":"111.394347ms","start":"2026-01-28T06:22:06.301903Z","end":"2026-01-28T06:22:06.413297Z","steps":["trace[1064227989] 'range keys from in-memory index tree' (duration: 111.061388ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.129615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T06:22:07.630454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511129137690775,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T06:22:07.73304Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137761893s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-28T06:22:07.84289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.598846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:22:07.843322Z","caller":"traceutil/trace.go:171","msg":"trace[919631274] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"1.248068159s","start":"2026-01-28T06:22:06.595203Z","end":"2026-01-28T06:22:07.843272Z","steps":["trace[919631274] 'process raft request' (duration: 1.137982019s)","trace[919631274] 'compare' (duration: 104.04051ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:07.843451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:06.595186Z","time spent":"1.248182632s","remote":"127.0.0.1:59970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:22:07.893841Z","caller":"traceutil/trace.go:171","msg":"trace[579846014] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:920; }","duration":"1.264466251s","start":"2026-01-28T06:22:06.629327Z","end":"2026-01-28T06:22:07.893793Z","steps":["trace[579846014] 'read index received' (duration: 1.103864988s)","trace[579846014] 'applied index is now lower than readState.Index' (duration: 160.600343ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:07.894125Z","caller":"traceutil/trace.go:171","msg":"trace[716199994] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"317.785349ms","start":"2026-01-28T06:22:07.576325Z","end":"2026-01-28T06:22:07.89411Z","steps":["trace[716199994] 'process raft request' (duration: 317.366247ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.894313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138934532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-28T06:22:07.894399Z","caller":"traceutil/trace.go:171","msg":"trace[1744139978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"1.139070575s","start":"2026-01-28T06:22:06.755304Z","end":"2026-01-28T06:22:07.894374Z","steps":["trace[1744139978] 'agreement among raft nodes before linearized reading' (duration: 1.13886138s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.894445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:06.755281Z","time spent":"1.139155248s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-28T06:22:07.894343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:07.576309Z","time spent":"317.937904ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:22:07.894668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265333365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:07.89471Z","caller":"traceutil/trace.go:171","msg":"trace[843205833] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:889; }","duration":"1.265373477s","start":"2026-01-28T06:22:06.629323Z","end":"2026-01-28T06:22:07.894697Z","steps":["trace[843205833] 'agreement among raft nodes before linearized reading' (duration: 1.265309565s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.894744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:06.629288Z","time spent":"1.265448689s","remote":"127.0.0.1:60394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:07.895157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.050138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-28T06:22:07.895331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040360119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:07.89537Z","caller":"traceutil/trace.go:171","msg":"trace[491216693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"1.04042103s","start":"2026-01-28T06:22:06.854939Z","end":"2026-01-28T06:22:07.89536Z","steps":["trace[491216693] 'agreement among raft nodes before linearized reading' (duration: 1.040346388s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.895403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:06.854925Z","time spent":"1.040472512s","remote":"127.0.0.1:59932","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-01-28T06:22:07.895347Z","caller":"traceutil/trace.go:171","msg":"trace[1755251012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"328.269205ms","start":"2026-01-28T06:22:07.567063Z","end":"2026-01-28T06:22:07.895333Z","steps":["trace[1755251012] 'agreement among raft nodes before linearized reading' (duration: 328.060969ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.895678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:07.567046Z","time spent":"328.617755ms","remote":"127.0.0.1:59946","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-01-28T06:22:07.896259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.451982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-28T06:22:07.896433Z","caller":"traceutil/trace.go:171","msg":"trace[1890028002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"218.649028ms","start":"2026-01-28T06:22:07.677771Z","end":"2026-01-28T06:22:07.89642Z","steps":["trace[1890028002] 'agreement among raft nodes before linearized reading' (duration: 218.435052ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.895266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.824614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-28T06:22:07.896858Z","caller":"traceutil/trace.go:171","msg":"trace[99099388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"660.437049ms","start":"2026-01-28T06:22:07.236408Z","end":"2026-01-28T06:22:07.896845Z","steps":["trace[99099388] 'agreement among raft nodes before linearized reading' (duration: 658.797573ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:07.896982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:07.236394Z","time spent":"660.572634ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T06:22:08.13296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.793819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:22:08.133055Z","caller":"traceutil/trace.go:171","msg":"trace[1818457656] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"223.85791ms","start":"2026-01-28T06:22:07.909182Z","end":"2026-01-28T06:22:08.13304Z","steps":["trace[1818457656] 'process raft request' (duration: 70.937418ms)","trace[1818457656] 'compare' (duration: 152.564212ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:08.133189Z","caller":"traceutil/trace.go:171","msg":"trace[1286687603] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:922; }","duration":"221.686288ms","start":"2026-01-28T06:22:07.911498Z","end":"2026-01-28T06:22:08.133184Z","steps":["trace[1286687603] 'read index received' (duration: 68.632453ms)","trace[1286687603] 'applied index is now lower than readState.Index' (duration: 153.053295ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:08.133241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.74075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-01-28T06:22:08.133253Z","caller":"traceutil/trace.go:171","msg":"trace[694404518] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:893; }","duration":"221.766161ms","start":"2026-01-28T06:22:07.911482Z","end":"2026-01-28T06:22:08.133249Z","steps":["trace[694404518] 'agreement among raft nodes before linearized reading' (duration: 221.71968ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:08.13331Z","caller":"traceutil/trace.go:171","msg":"trace[988217749] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"223.918981ms","start":"2026-01-28T06:22:07.909367Z","end":"2026-01-28T06:22:08.133286Z","steps":["trace[988217749] 'process raft request' (duration: 223.652504ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:08.133358Z","caller":"traceutil/trace.go:171","msg":"trace[881146838] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"218.366595ms","start":"2026-01-28T06:22:07.914988Z","end":"2026-01-28T06:22:08.133354Z","steps":["trace[881146838] 'process raft request' (duration: 218.17888ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:08.133342Z","caller":"traceutil/trace.go:171","msg":"trace[1725971444] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"218.384526ms","start":"2026-01-28T06:22:07.914953Z","end":"2026-01-28T06:22:08.133337Z","steps":["trace[1725971444] 'process raft request' (duration: 218.1806ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:10.33549Z","caller":"traceutil/trace.go:171","msg":"trace[1737277514] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"189.983851ms","start":"2026-01-28T06:22:10.145487Z","end":"2026-01-28T06:22:10.335471Z","steps":["trace[1737277514] 'process raft request' (duration: 189.896849ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:10.886357Z","caller":"traceutil/trace.go:171","msg":"trace[755477742] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:928; }","duration":"739.723351ms","start":"2026-01-28T06:22:10.146611Z","end":"2026-01-28T06:22:10.886334Z","steps":["trace[755477742] 'read index received' (duration: 188.885221ms)","trace[755477742] 'applied index is now lower than readState.Index' (duration: 550.83724ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:10.886601Z","caller":"traceutil/trace.go:171","msg":"trace[1609064696] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"740.857492ms","start":"2026-01-28T06:22:10.14573Z","end":"2026-01-28T06:22:10.886588Z","steps":["trace[1609064696] 'process raft request' (duration: 740.471051ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:10.886698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:10.145721Z","time spent":"740.913753ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:22:10.886862Z","caller":"traceutil/trace.go:171","msg":"trace[1482434921] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"741.083798ms","start":"2026-01-28T06:22:10.145768Z","end":"2026-01-28T06:22:10.886851Z","steps":["trace[1482434921] 'process raft request' (duration: 740.530783ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:10.886911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:10.145764Z","time spent":"741.119799ms","remote":"127.0.0.1:60216","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-01-28T06:22:10.887209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.587434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-28T06:22:10.887277Z","caller":"traceutil/trace.go:171","msg":"trace[317058747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"740.659026ms","start":"2026-01-28T06:22:10.146595Z","end":"2026-01-28T06:22:10.887254Z","steps":["trace[317058747] 'agreement among raft nodes before linearized reading' (duration: 740.499071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:10.887518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:10.146589Z","time spent":"740.912223ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-28T06:22:10.887895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.845338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:10.887938Z","caller":"traceutil/trace.go:171","msg":"trace[88293319] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:897; }","duration":"139.928751ms","start":"2026-01-28T06:22:10.747998Z","end":"2026-01-28T06:22:10.887926Z","steps":["trace[88293319] 'agreement among raft nodes before linearized reading' (duration: 139.857039ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:10.888203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.997468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-28T06:22:10.888242Z","caller":"traceutil/trace.go:171","msg":"trace[15436219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"656.062791ms","start":"2026-01-28T06:22:10.232169Z","end":"2026-01-28T06:22:10.888232Z","steps":["trace[15436219] 'agreement among raft nodes before linearized reading' (duration: 655.962608ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:10.888274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:10.232156Z","time spent":"656.112102ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-28T06:22:11.154985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.743704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:22:11.155589Z","caller":"traceutil/trace.go:171","msg":"trace[723849187] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"263.500855ms","start":"2026-01-28T06:22:10.892069Z","end":"2026-01-28T06:22:11.15557Z","steps":["trace[723849187] 'process raft request' (duration: 114.103513ms)","trace[723849187] 'compare' (duration: 148.545969ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:11.155693Z","caller":"traceutil/trace.go:171","msg":"trace[665644307] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"262.992701ms","start":"2026-01-28T06:22:10.892673Z","end":"2026-01-28T06:22:11.155666Z","steps":["trace[665644307] 'process raft request' (duration: 262.531239ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:12.229374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.31351ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-28T06:22:12.501326Z","caller":"traceutil/trace.go:171","msg":"trace[731049998] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"160.998026ms","start":"2026-01-28T06:22:12.340302Z","end":"2026-01-28T06:22:12.5013Z","steps":["trace[731049998] 'process raft request' (duration: 160.823391ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:13.037576Z","caller":"traceutil/trace.go:171","msg":"trace[1313960758] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"140.715683ms","start":"2026-01-28T06:22:12.896837Z","end":"2026-01-28T06:22:13.037553Z","steps":["trace[1313960758] 'process raft request' (duration: 140.576999ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:13.188227Z","caller":"traceutil/trace.go:171","msg":"trace[1905657150] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"213.007978ms","start":"2026-01-28T06:22:12.975198Z","end":"2026-01-28T06:22:13.188206Z","steps":["trace[1905657150] 'read index received' (duration: 62.861461ms)","trace[1905657150] 'applied index is now lower than readState.Index' (duration: 150.145547ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:13.188547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.323026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-01-28T06:22:13.188661Z","caller":"traceutil/trace.go:171","msg":"trace[1763451195] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:903; }","duration":"213.438699ms","start":"2026-01-28T06:22:12.975194Z","end":"2026-01-28T06:22:13.188633Z","steps":["trace[1763451195] 'agreement among raft nodes before linearized reading' (duration: 213.147842ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:13.189238Z","caller":"traceutil/trace.go:171","msg":"trace[1680757744] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"292.133804ms","start":"2026-01-28T06:22:12.897085Z","end":"2026-01-28T06:22:13.189218Z","steps":["trace[1680757744] 'process raft request' (duration: 281.698096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:14.692989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192319529s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:22:14.693057Z","caller":"traceutil/trace.go:171","msg":"trace[74681276] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"1.128069308s","start":"2026-01-28T06:22:13.56497Z","end":"2026-01-28T06:22:14.693039Z","steps":["trace[74681276] 'read index received' (duration: 28.941µs)","trace[74681276] 'applied index is now lower than readState.Index' (duration: 1.128039357s)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:14.693378Z","caller":"traceutil/trace.go:171","msg":"trace[43056358] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.495631355s","start":"2026-01-28T06:22:13.197729Z","end":"2026-01-28T06:22:14.69336Z","steps":["trace[43056358] 'process raft request' (duration: 302.886915ms)","trace[43056358] 'compare' (duration: 1.191535628s)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:14.693451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:13.197632Z","time spent":"1.495783719s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:22:14.693654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128680984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:14.693687Z","caller":"traceutil/trace.go:171","msg":"trace[895396740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"1.128736556s","start":"2026-01-28T06:22:13.564939Z","end":"2026-01-28T06:22:14.693675Z","steps":["trace[895396740] 'agreement among raft nodes before linearized reading' (duration: 1.128687075s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:14.693713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:13.564923Z","time spent":"1.128784567s","remote":"127.0.0.1:59932","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-01-28T06:22:14.693861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.898923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:14.69389Z","caller":"traceutil/trace.go:171","msg":"trace[1029070743] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:905; }","duration":"429.966234ms","start":"2026-01-28T06:22:14.263915Z","end":"2026-01-28T06:22:14.693881Z","steps":["trace[1029070743] 'agreement among raft nodes before linearized reading' (duration: 429.923783ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:14.693916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:14.263896Z","time spent":"430.013005ms","remote":"127.0.0.1:54906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:14.694415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.624563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-28T06:22:14.694456Z","caller":"traceutil/trace.go:171","msg":"trace[900559933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:905; }","duration":"189.674784ms","start":"2026-01-28T06:22:14.504771Z","end":"2026-01-28T06:22:14.694446Z","steps":["trace[900559933] 'agreement among raft nodes before linearized reading' (duration: 189.553931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:14.691837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311516963s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:14.694579Z","caller":"traceutil/trace.go:171","msg":"trace[452003672] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:904; }","duration":"1.314276345s","start":"2026-01-28T06:22:13.380293Z","end":"2026-01-28T06:22:14.694569Z","steps":["trace[452003672] 'range keys from in-memory index tree' (duration: 1.311501822s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:14.695195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.737083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-01-28T06:22:14.695239Z","caller":"traceutil/trace.go:171","msg":"trace[955760547] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:905; }","duration":"190.782414ms","start":"2026-01-28T06:22:14.504445Z","end":"2026-01-28T06:22:14.695227Z","steps":["trace[955760547] 'agreement among raft nodes before linearized reading' (duration: 190.673101ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:14.882037Z","caller":"traceutil/trace.go:171","msg":"trace[1156971262] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"180.069191ms","start":"2026-01-28T06:22:14.701958Z","end":"2026-01-28T06:22:14.882027Z","steps":["trace[1156971262] 'process raft request' (duration: 97.945634ms)","trace[1156971262] 'compare' (duration: 81.746497ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:14.881931Z","caller":"traceutil/trace.go:171","msg":"trace[187799327] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"177.822832ms","start":"2026-01-28T06:22:14.704089Z","end":"2026-01-28T06:22:14.881912Z","steps":["trace[187799327] 'read index received' (duration: 95.771827ms)","trace[187799327] 'applied index is now lower than readState.Index' (duration: 82.050164ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:14.882369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.198671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:14.882401Z","caller":"traceutil/trace.go:171","msg":"trace[1511328175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"178.313865ms","start":"2026-01-28T06:22:14.704079Z","end":"2026-01-28T06:22:14.882393Z","steps":["trace[1511328175] 'agreement among raft nodes before linearized reading' (duration: 178.193222ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:15.559709Z","caller":"traceutil/trace.go:171","msg":"trace[1962162353] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"297.329284ms","start":"2026-01-28T06:22:15.262356Z","end":"2026-01-28T06:22:15.559685Z","steps":["trace[1962162353] 'process raft request' (duration: 297.153029ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:15.676898Z","caller":"traceutil/trace.go:171","msg":"trace[195100599] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:941; }","duration":"165.21113ms","start":"2026-01-28T06:22:15.511669Z","end":"2026-01-28T06:22:15.67688Z","steps":["trace[195100599] 'read index received' (duration: 47.915533ms)","trace[195100599] 'applied index is now lower than readState.Index' (duration: 117.295057ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:15.677086Z","caller":"traceutil/trace.go:171","msg":"trace[284789803] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"414.529538ms","start":"2026-01-28T06:22:15.262534Z","end":"2026-01-28T06:22:15.677064Z","steps":["trace[284789803] 'process raft request' (duration: 379.784599ms)","trace[284789803] 'compare' (duration: 34.487262ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:15.677146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:15.262527Z","time spent":"414.575379ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:22:15.67725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.57835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:15.677268Z","caller":"traceutil/trace.go:171","msg":"trace[970583151] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:909; }","duration":"165.6232ms","start":"2026-01-28T06:22:15.511638Z","end":"2026-01-28T06:22:15.677262Z","steps":["trace[970583151] 'agreement among raft nodes before linearized reading' (duration: 165.592739ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:15.677369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.871511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:15.677383Z","caller":"traceutil/trace.go:171","msg":"trace[1611674517] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:909; }","duration":"164.885791ms","start":"2026-01-28T06:22:15.512493Z","end":"2026-01-28T06:22:15.677379Z","steps":["trace[1611674517] 'agreement among raft nodes before linearized reading' (duration: 164.85815ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:17.880304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.643327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-28T06:22:17.880445Z","caller":"traceutil/trace.go:171","msg":"trace[1489190672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"199.829862ms","start":"2026-01-28T06:22:17.680595Z","end":"2026-01-28T06:22:17.880425Z","steps":["trace[1489190672] 'range keys from in-memory index tree' (duration: 199.143743ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:18.197745Z","caller":"traceutil/trace.go:171","msg":"trace[546382933] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"313.801443ms","start":"2026-01-28T06:22:17.883917Z","end":"2026-01-28T06:22:18.197719Z","steps":["trace[546382933] 'process raft request' (duration: 313.65432ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:18.197884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:17.883898Z","time spent":"313.915567ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T06:22:18.381699Z","caller":"traceutil/trace.go:171","msg":"trace[1153548228] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"134.193705ms","start":"2026-01-28T06:22:18.247478Z","end":"2026-01-28T06:22:18.381672Z","steps":["trace[1153548228] 'process raft request' (duration: 56.713633ms)","trace[1153548228] 'compare' (duration: 77.39722ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:18.858639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.870168ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-01-28T06:22:18.858722Z","caller":"traceutil/trace.go:171","msg":"trace[937664103] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"191.566835ms","start":"2026-01-28T06:22:18.667135Z","end":"2026-01-28T06:22:18.858702Z","steps":["trace[937664103] 'read index received' (duration: 22.58µs)","trace[937664103] 'applied index is now lower than readState.Index' (duration: 191.543155ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:18.85902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.855543ms","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-01-28T06:22:18.85906Z","caller":"traceutil/trace.go:171","msg":"trace[1726840388] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:917; }","duration":"191.926475ms","start":"2026-01-28T06:22:18.667115Z","end":"2026-01-28T06:22:18.859042Z","steps":["trace[1726840388] 'agreement among raft nodes before linearized reading' (duration: 191.832463ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:19.078221Z","caller":"traceutil/trace.go:171","msg":"trace[1376251294] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"126.831641ms","start":"2026-01-28T06:22:18.95137Z","end":"2026-01-28T06:22:19.078201Z","steps":["trace[1376251294] 'read index received' (duration: 46.196985ms)","trace[1376251294] 'applied index is now lower than readState.Index' (duration: 80.633986ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:19.078369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.987725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-28T06:22:19.078385Z","caller":"traceutil/trace.go:171","msg":"trace[846195741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:919; }","duration":"127.046727ms","start":"2026-01-28T06:22:18.951333Z","end":"2026-01-28T06:22:19.07838Z","steps":["trace[846195741] 'agreement among raft nodes before linearized reading' (duration: 126.937854ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:19.078453Z","caller":"traceutil/trace.go:171","msg":"trace[1950729046] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"216.317498ms","start":"2026-01-28T06:22:18.862129Z","end":"2026-01-28T06:22:19.078447Z","steps":["trace[1950729046] 'process raft request' (duration: 135.467477ms)","trace[1950729046] 'compare' (duration: 80.504873ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:19.31691Z","caller":"traceutil/trace.go:171","msg":"trace[977085358] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"166.032579ms","start":"2026-01-28T06:22:19.150854Z","end":"2026-01-28T06:22:19.316887Z","steps":["trace[977085358] 'read index received' (duration: 94.294909ms)","trace[977085358] 'applied index is now lower than readState.Index' (duration: 71.7367ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:19.317658Z","caller":"traceutil/trace.go:171","msg":"trace[1959389550] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"233.480051ms","start":"2026-01-28T06:22:19.084161Z","end":"2026-01-28T06:22:19.317641Z","steps":["trace[1959389550] 'process raft request' (duration: 160.999512ms)","trace[1959389550] 'compare' (duration: 71.516554ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:19.318944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.07344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-28T06:22:19.319166Z","caller":"traceutil/trace.go:171","msg":"trace[630487801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"168.156483ms","start":"2026-01-28T06:22:19.150828Z","end":"2026-01-28T06:22:19.318985Z","steps":["trace[630487801] 'agreement among raft nodes before linearized reading' (duration: 168.007019ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:20.362049Z","caller":"traceutil/trace.go:171","msg":"trace[1308595013] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"160.580385ms","start":"2026-01-28T06:22:20.201442Z","end":"2026-01-28T06:22:20.362023Z","steps":["trace[1308595013] 'read index received' (duration: 131.625301ms)","trace[1308595013] 'applied index is now lower than readState.Index' (duration: 28.954154ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:20.362786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.330545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T06:22:20.362959Z","caller":"traceutil/trace.go:171","msg":"trace[41327558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:931; }","duration":"161.542ms","start":"2026-01-28T06:22:20.201406Z","end":"2026-01-28T06:22:20.362948Z","steps":["trace[41327558] 'agreement among raft nodes before linearized reading' (duration: 161.216361ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:20.362823Z","caller":"traceutil/trace.go:171","msg":"trace[341026302] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"222.132195ms","start":"2026-01-28T06:22:20.140674Z","end":"2026-01-28T06:22:20.362806Z","steps":["trace[341026302] 'process raft request' (duration: 192.514675ms)","trace[341026302] 'compare' (duration: 28.740558ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:23.906581Z","caller":"traceutil/trace.go:171","msg":"trace[3928778] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"110.742829ms","start":"2026-01-28T06:22:23.795552Z","end":"2026-01-28T06:22:23.906295Z","steps":["trace[3928778] 'process raft request' (duration: 74.155297ms)","trace[3928778] 'compare' (duration: 36.461949ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:28.757227Z","caller":"traceutil/trace.go:171","msg":"trace[344231237] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"284.544898ms","start":"2026-01-28T06:22:28.472655Z","end":"2026-01-28T06:22:28.7572Z","steps":["trace[344231237] 'read index received' (duration: 284.432796ms)","trace[344231237] 'applied index is now lower than readState.Index' (duration: 111.002µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:22:28.757336Z","caller":"traceutil/trace.go:171","msg":"trace[289853904] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"344.060548ms","start":"2026-01-28T06:22:28.413265Z","end":"2026-01-28T06:22:28.757325Z","steps":["trace[289853904] 'process raft request' (duration: 343.788332ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:28.757439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:28.413241Z","time spent":"344.12234ms","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:22:28.757633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.974088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-28T06:22:28.757663Z","caller":"traceutil/trace.go:171","msg":"trace[1894044267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"285.0287ms","start":"2026-01-28T06:22:28.472623Z","end":"2026-01-28T06:22:28.757652Z","steps":["trace[1894044267] 'agreement among raft nodes before linearized reading' (duration: 284.924097ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:28.764549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.29278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:28.764602Z","caller":"traceutil/trace.go:171","msg":"trace[1867463803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"197.375542ms","start":"2026-01-28T06:22:28.567211Z","end":"2026-01-28T06:22:28.764587Z","steps":["trace[1867463803] 'agreement among raft nodes before linearized reading' (duration: 197.28009ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:22:28.764763Z","caller":"traceutil/trace.go:171","msg":"trace[788410460] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"234.007718ms","start":"2026-01-28T06:22:28.530749Z","end":"2026-01-28T06:22:28.764757Z","steps":["trace[788410460] 'process raft request' (duration: 233.675ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:30.767572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999268397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:30.767768Z","caller":"traceutil/trace.go:171","msg":"trace[1271141477] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999483302s","start":"2026-01-28T06:22:28.768255Z","end":"2026-01-28T06:22:30.767738Z","steps":["trace[1271141477] 'agreement among raft nodes before linearized reading' (duration: 1.999280737s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:30.767924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:28.768248Z","time spent":"1.999626066s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:30 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:32.774449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00006374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:32.774765Z","caller":"traceutil/trace.go:171","msg":"trace[83815773] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000393317s","start":"2026-01-28T06:22:30.774323Z","end":"2026-01-28T06:22:32.774716Z","steps":["trace[83815773] 'agreement among raft nodes before linearized reading' (duration: 2.00008577s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:32.775288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:30.77431Z","time spent":"2.000960931s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:34.444866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999484262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:34.445889Z","caller":"traceutil/trace.go:171","msg":"trace[907843548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000568466s","start":"2026-01-28T06:22:29.445295Z","end":"2026-01-28T06:22:34.445864Z","steps":["trace[907843548] 'agreement among raft nodes before linearized reading' (duration: 4.999528863s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:34.446199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:29.44526Z","time spent":"5.000921973s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T06:22:34.516535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999592289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:34.516616Z","caller":"traceutil/trace.go:171","msg":"trace[486775275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999727141s","start":"2026-01-28T06:22:29.516868Z","end":"2026-01-28T06:22:34.516595Z","steps":["trace[486775275] 'agreement among raft nodes before linearized reading' (duration: 4.999611969s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:34.516667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:29.516852Z","time spent":"4.999805364s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-28T06:22:34.775482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999859065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:34.775574Z","caller":"traceutil/trace.go:171","msg":"trace[358890705] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000011658s","start":"2026-01-28T06:22:32.775543Z","end":"2026-01-28T06:22:34.775554Z","steps":["trace[358890705] 'agreement among raft nodes before linearized reading' (duration: 1.999896026s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:34.775873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:32.775522Z","time spent":"2.000337475s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T06:22:34.944097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999174479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:34.944177Z","caller":"traceutil/trace.go:171","msg":"trace[2012355959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999299962s","start":"2026-01-28T06:22:29.944857Z","end":"2026-01-28T06:22:34.944157Z","steps":["trace[2012355959] 'agreement among raft nodes before linearized reading' (duration: 4.99920001s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:34.944227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:29.944774Z","time spent":"4.999441326s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-28T06:22:35.768686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:28.7681Z","time spent":"7.000579196s","remote":"127.0.0.1:60012","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:35.769022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:28.768365Z","time spent":"7.000654417s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:36.783893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000280973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:36.784074Z","caller":"traceutil/trace.go:171","msg":"trace[1966509065] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000472557s","start":"2026-01-28T06:22:34.78358Z","end":"2026-01-28T06:22:36.784052Z","steps":["trace[1966509065] 'agreement among raft nodes before linearized reading' (duration: 2.000277542s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:36.784146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:34.783524Z","time spent":"2.00060962s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:37.771211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:30.770616Z","time spent":"7.000589538s","remote":"127.0.0.1:60012","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:38.470519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.993916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:38.470595Z","caller":"traceutil/trace.go:171","msg":"trace[1295676450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"695.098588ms","start":"2026-01-28T06:22:37.775476Z","end":"2026-01-28T06:22:38.470574Z","steps":["trace[1295676450] 'agreement among raft nodes before linearized reading' (duration: 695.008797ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:38.470639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:37.775468Z","time spent":"695.16122ms","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/01/28 06:22:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:38.536561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:31.535837Z","time spent":"7.000718123s","remote":"127.0.0.1:60126","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:38.784777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000612231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:38.784858Z","caller":"traceutil/trace.go:171","msg":"trace[1631433695] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000737063s","start":"2026-01-28T06:22:36.7841Z","end":"2026-01-28T06:22:38.784837Z","steps":["trace[1631433695] 'agreement among raft nodes before linearized reading' (duration: 2.000640601s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:38.784904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:36.784082Z","time spent":"2.000812445s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:38.855425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000191018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:38.855812Z","caller":"traceutil/trace.go:171","msg":"trace[1199091212] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000603327s","start":"2026-01-28T06:22:36.855189Z","end":"2026-01-28T06:22:38.855792Z","steps":["trace[1199091212] 'agreement among raft nodes before linearized reading' (duration: 2.000209918s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:38.856644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:36.855179Z","time spent":"2.001448665s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T06:22:38.913461Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"0e099c03429b38d7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-01-28T06:22:39.276025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999804547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:39.276267Z","caller":"traceutil/trace.go:171","msg":"trace[321528428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.000097394s","start":"2026-01-28T06:22:29.276147Z","end":"2026-01-28T06:22:39.276244Z","steps":["trace[321528428] 'agreement among raft nodes before linearized reading' (duration: 9.999821768s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:39.276359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:29.27613Z","time spent":"10.000215986s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/01/28 06:22:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:39.445277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.994710942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:39.445665Z","caller":"traceutil/trace.go:171","msg":"trace[851887866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.994829215s","start":"2026-01-28T06:22:36.45051Z","end":"2026-01-28T06:22:39.44534Z","steps":["trace[851887866] 'agreement among raft nodes before linearized reading' (duration: 2.994727513s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:39.44627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:36.450497Z","time spent":"2.995563541s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/01/28 06:22:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:39.517913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999499581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:39.51808Z","caller":"traceutil/trace.go:171","msg":"trace[430455091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.999692966s","start":"2026-01-28T06:22:36.518366Z","end":"2026-01-28T06:22:39.518059Z","steps":["trace[430455091] 'agreement among raft nodes before linearized reading' (duration: 2.999518542s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:39.518179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:36.518356Z","time spent":"2.999810438s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/01/28 06:22:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:39.53288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:32.531948Z","time spent":"7.000926863s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:39.965896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.020884397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:39.965977Z","caller":"traceutil/trace.go:171","msg":"trace[1557791421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"3.021001319s","start":"2026-01-28T06:22:36.944952Z","end":"2026-01-28T06:22:39.965953Z","steps":["trace[1557791421] 'agreement among raft nodes before linearized reading' (duration: 3.020907327s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:39.967987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:36.944941Z","time spent":"3.021131202s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/01/28 06:22:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:40.794605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000215855s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:40.794825Z","caller":"traceutil/trace.go:171","msg":"trace[987292760] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00047626s","start":"2026-01-28T06:22:38.794328Z","end":"2026-01-28T06:22:40.794804Z","steps":["trace[987292760] 'agreement among raft nodes before linearized reading' (duration: 2.000241525s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:40.794903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:38.794273Z","time spent":"2.000617103s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:42.794984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999707196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:42.795061Z","caller":"traceutil/trace.go:171","msg":"trace[479335407] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999831039s","start":"2026-01-28T06:22:40.795211Z","end":"2026-01-28T06:22:42.795042Z","steps":["trace[479335407] 'agreement among raft nodes before linearized reading' (duration: 1.999736997s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:42.795104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:40.795191Z","time spent":"1.99990472s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:42 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:44.446511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:39.449122Z","time spent":"4.997382497s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/01/28 06:22:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:44.517824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:39.518856Z","time spent":"4.998962973s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/01/28 06:22:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:44.802763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000418784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:44.802845Z","caller":"traceutil/trace.go:171","msg":"trace[1929244398] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000556697s","start":"2026-01-28T06:22:42.802268Z","end":"2026-01-28T06:22:44.802825Z","steps":["trace[1929244398] 'agreement among raft nodes before linearized reading' (duration: 2.000449294s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:44.802897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:42.802247Z","time spent":"2.000634938s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T06:22:45.472989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:38.472685Z","time spent":"7.000296617s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:45.479638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:39.279737Z","time spent":"6.199893623s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/01/28 06:22:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:45.923917Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"0e099c03429b38d7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-01-28T06:22:46.11352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:39.113205Z","time spent":"7.00030979s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:46.537214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:39.536409Z","time spent":"7.000798135s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:46.803577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000144232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:46.803675Z","caller":"traceutil/trace.go:171","msg":"trace[2081899818] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000300166s","start":"2026-01-28T06:22:44.80335Z","end":"2026-01-28T06:22:46.80365Z","steps":["trace[2081899818] 'agreement among raft nodes before linearized reading' (duration: 2.000176443s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:46.80397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:44.803287Z","time spent":"2.000664743s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:48.426276Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"0e099c03429b3952","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-01-28T06:22:48.538841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999292478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:48.538919Z","caller":"traceutil/trace.go:171","msg":"trace[952281448] range","detail":"{range_begin:/registry/minions/instance; range_end:; }","duration":"9.99940382s","start":"2026-01-28T06:22:38.539496Z","end":"2026-01-28T06:22:48.538899Z","steps":["trace[952281448] 'agreement among raft nodes before linearized reading' (duration: 9.999312908s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:48.538964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:38.539483Z","time spent":"9.999472742s","remote":"127.0.0.1:60126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":0,"response size":0,"request content":"key:\"/registry/minions/instance\" "} 2026/01/28 06:22:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:48.812009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000180891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:48.812079Z","caller":"traceutil/trace.go:171","msg":"trace[716586329] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000283983s","start":"2026-01-28T06:22:46.811777Z","end":"2026-01-28T06:22:48.812061Z","steps":["trace[716586329] 'agreement among raft nodes before linearized reading' (duration: 2.000203821s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:48.812119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:46.811764Z","time spent":"2.000347854s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:50.81247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0000971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:50.812553Z","caller":"traceutil/trace.go:171","msg":"trace[1918174105] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000241453s","start":"2026-01-28T06:22:48.812291Z","end":"2026-01-28T06:22:50.812533Z","steps":["trace[1918174105] 'agreement among raft nodes before linearized reading' (duration: 2.000139551s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:50.8126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:48.812271Z","time spent":"2.000319544s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T06:22:52.818302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999967807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-28T06:22:52.818377Z","caller":"traceutil/trace.go:171","msg":"trace[951324415] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000090189s","start":"2026-01-28T06:22:50.818267Z","end":"2026-01-28T06:22:52.818357Z","steps":["trace[951324415] 'agreement among raft nodes before linearized reading' (duration: 1.999997327s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:52.818414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:50.818247Z","time spent":"2.000160451s","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-28T06:22:52.930315Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"0e099c03429b38d7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-01-28T06:22:53.117961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:46.11759Z","time spent":"7.000364286s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:53.543806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:46.542739Z","time spent":"7.001060359s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:54.819003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999817307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-28T06:22:54.819081Z","caller":"traceutil/trace.go:171","msg":"trace[1840343301] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99994379s","start":"2026-01-28T06:22:52.819118Z","end":"2026-01-28T06:22:54.819062Z","steps":["trace[1840343301] 'agreement among raft nodes before linearized reading' (duration: 1.999850428s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:54.819129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:52.819099Z","time spent":"2.000017691s","remote":"127.0.0.1:59946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/28 06:22:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-28T06:22:55.432491Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"0e099c03429b3952","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-01-28T06:22:56.162556Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"27.303682527s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-28T06:22:56.183043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:54.160106Z","time spent":"2.022929452s","remote":"127.0.0.1:51644","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-28T06:22:56.184124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"22.804024278s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.184237Z","caller":"traceutil/trace.go:171","msg":"trace[1771567300] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:969; }","duration":"22.80414525s","start":"2026-01-28T06:22:33.380075Z","end":"2026-01-28T06:22:56.18422Z","steps":["trace[1771567300] 'range keys from in-memory index tree' (duration: 22.804011578s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.185044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.76098954s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.185285Z","caller":"traceutil/trace.go:171","msg":"trace[1596846996] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:969; }","duration":"7.761196234s","start":"2026-01-28T06:22:48.424028Z","end":"2026-01-28T06:22:56.185225Z","steps":["trace[1596846996] 'range keys from in-memory index tree' (duration: 7.76097541s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.185885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"27.326896518s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-28T06:22:56.186095Z","caller":"traceutil/trace.go:171","msg":"trace[968069503] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"27.417808021s","start":"2026-01-28T06:22:28.768274Z","end":"2026-01-28T06:22:56.186082Z","steps":["trace[968069503] 'read index received' (duration: 90.668539ms)","trace[968069503] 'applied index is now lower than readState.Index' (duration: 27.327138602s)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:22:56.207407Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"240.144µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-01-28T06:22:56.214513Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"9.69µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-01-28T06:22:56.215458Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"814.205µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-01-28T06:22:56.215568Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"9.38µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-01-28T06:22:56.215764Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"0e099c03429b38d7","error":"lease not found"} {"level":"info","ts":"2026-01-28T06:22:56.21595Z","caller":"traceutil/trace.go:171","msg":"trace[1894369079] transaction","detail":"{read_only:false; number_of_response:1; response_revision:979; }","duration":"3.094218299s","start":"2026-01-28T06:22:53.121715Z","end":"2026-01-28T06:22:56.215933Z","steps":["trace[1894369079] 'process raft request' (duration: 3.093978735s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.21754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:53.121693Z","time spent":"3.094348023s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:22:56.21589Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"8.851µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"warn","ts":"2026-01-28T06:22:56.217955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.723880758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.218009Z","caller":"traceutil/trace.go:171","msg":"trace[1451906442] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:979; }","duration":"13.7239694s","start":"2026-01-28T06:22:42.49403Z","end":"2026-01-28T06:22:56.217999Z","steps":["trace[1451906442] 'agreement among raft nodes before linearized reading' (duration: 13.723897058s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.218088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:42.494012Z","time spent":"13.724030661s","remote":"127.0.0.1:32968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-01-28T06:22:56.21838Z","caller":"traceutil/trace.go:171","msg":"trace[1404121961] transaction","detail":"{read_only:false; number_of_response:1; response_revision:979; }","duration":"2.669775195s","start":"2026-01-28T06:22:53.548595Z","end":"2026-01-28T06:22:56.21837Z","steps":["trace[1404121961] 'process raft request' (duration: 2.667128144s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.218461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:53.548573Z","time spent":"2.669863816s","remote":"127.0.0.1:60216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T06:22:56.218591Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"0e099c03429b3952","error":"lease not found"} {"level":"warn","ts":"2026-01-28T06:22:56.218654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245166999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.218688Z","caller":"traceutil/trace.go:171","msg":"trace[1099738290] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:979; }","duration":"1.24522139s","start":"2026-01-28T06:22:54.973459Z","end":"2026-01-28T06:22:56.218681Z","steps":["trace[1099738290] 'agreement among raft nodes before linearized reading' (duration: 1.245173219s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.218806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:54.973445Z","time spent":"1.245352853s","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.218981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394650386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.219032Z","caller":"traceutil/trace.go:171","msg":"trace[401981035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"1.394735038s","start":"2026-01-28T06:22:54.82429Z","end":"2026-01-28T06:22:56.219025Z","steps":["trace[401981035] 'agreement among raft nodes before linearized reading' (duration: 1.394653787s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.219065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:54.824274Z","time spent":"1.394785449s","remote":"127.0.0.1:59932","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-01-28T06:22:56.219249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.893782076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:56.219307Z","caller":"traceutil/trace.go:171","msg":"trace[305078595] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:979; }","duration":"2.893874228s","start":"2026-01-28T06:22:53.325417Z","end":"2026-01-28T06:22:56.219292Z","steps":["trace[305078595] 'agreement among raft nodes before linearized reading' (duration: 2.893805326s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.219364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:53.325398Z","time spent":"2.893943818s","remote":"127.0.0.1:60280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.219512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.62183667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:56.219563Z","caller":"traceutil/trace.go:171","msg":"trace[1298149312] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:979; }","duration":"3.621911311s","start":"2026-01-28T06:22:52.597644Z","end":"2026-01-28T06:22:56.219556Z","steps":["trace[1298149312] 'agreement among raft nodes before linearized reading' (duration: 3.621835709s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.2196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:52.597634Z","time spent":"3.621959382s","remote":"127.0.0.1:60444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.21974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.734600289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.21979Z","caller":"traceutil/trace.go:171","msg":"trace[1536996578] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:979; }","duration":"3.734687061s","start":"2026-01-28T06:22:52.485096Z","end":"2026-01-28T06:22:56.219784Z","steps":["trace[1536996578] 'agreement among raft nodes before linearized reading' (duration: 3.73462375s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.219826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:52.485076Z","time spent":"3.734741522s","remote":"127.0.0.1:60160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.219963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.529111593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:56.220087Z","caller":"traceutil/trace.go:171","msg":"trace[142996567] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:979; }","duration":"7.529160444s","start":"2026-01-28T06:22:48.690844Z","end":"2026-01-28T06:22:56.220005Z","steps":["trace[142996567] 'agreement among raft nodes before linearized reading' (duration: 7.529099023s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.220132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:48.690785Z","time spent":"7.529338487s","remote":"127.0.0.1:60122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.220365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.679797415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5052"} {"level":"info","ts":"2026-01-28T06:22:56.220417Z","caller":"traceutil/trace.go:171","msg":"trace[618063540] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:979; }","duration":"7.679884516s","start":"2026-01-28T06:22:48.540525Z","end":"2026-01-28T06:22:56.22041Z","steps":["trace[618063540] 'agreement among raft nodes before linearized reading' (duration: 7.679768304s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.220453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:48.540507Z","time spent":"7.679940037s","remote":"127.0.0.1:60126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5075,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-01-28T06:22:56.2206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.638790158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:56.220651Z","caller":"traceutil/trace.go:171","msg":"trace[601366479] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:979; }","duration":"10.638849489s","start":"2026-01-28T06:22:45.581795Z","end":"2026-01-28T06:22:56.220644Z","steps":["trace[601366479] 'agreement among raft nodes before linearized reading' (duration: 10.638778488s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.220686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:45.581735Z","time spent":"10.638944171s","remote":"127.0.0.1:60126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.221346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"20.858367618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.221426Z","caller":"traceutil/trace.go:171","msg":"trace[1912254037] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:979; }","duration":"20.858482011s","start":"2026-01-28T06:22:35.362935Z","end":"2026-01-28T06:22:56.221417Z","steps":["trace[1912254037] 'agreement among raft nodes before linearized reading' (duration: 20.858390829s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.221465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:35.362917Z","time spent":"20.858539682s","remote":"127.0.0.1:60074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.22167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"16.419920164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:56.22173Z","caller":"traceutil/trace.go:171","msg":"trace[342820614] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:979; }","duration":"16.420043127s","start":"2026-01-28T06:22:39.80168Z","end":"2026-01-28T06:22:56.221723Z","steps":["trace[342820614] 'agreement among raft nodes before linearized reading' (duration: 16.419947445s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.221814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:39.801655Z","time spent":"16.420125019s","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.222023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"16.638508105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:56.222097Z","caller":"traceutil/trace.go:171","msg":"trace[714483423] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:979; }","duration":"16.638608777s","start":"2026-01-28T06:22:39.583482Z","end":"2026-01-28T06:22:56.222091Z","steps":["trace[714483423] 'agreement among raft nodes before linearized reading' (duration: 16.638489554s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.222167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:39.583471Z","time spent":"16.638662828s","remote":"127.0.0.1:60312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.224474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"18.449549744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.188ed0dce5a50ada\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-01-28T06:22:56.224638Z","caller":"traceutil/trace.go:171","msg":"trace[1811873189] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.188ed0dce5a50ada; range_end:; response_count:1; response_revision:979; }","duration":"18.449743028s","start":"2026-01-28T06:22:37.774877Z","end":"2026-01-28T06:22:56.22462Z","steps":["trace[1811873189] 'agreement among raft nodes before linearized reading' (duration: 18.447453824s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.224985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:37.774863Z","time spent":"18.450061823s","remote":"127.0.0.1:60012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.188ed0dce5a50ada\" "} {"level":"warn","ts":"2026-01-28T06:22:56.22541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"18.505428686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T06:22:56.225519Z","caller":"traceutil/trace.go:171","msg":"trace[899545608] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:979; }","duration":"18.505566889s","start":"2026-01-28T06:22:37.71994Z","end":"2026-01-28T06:22:56.225507Z","steps":["trace[899545608] 'agreement among raft nodes before linearized reading' (duration: 18.505405966s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.225609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:37.719882Z","time spent":"18.505715032s","remote":"127.0.0.1:60336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.22605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"19.840427588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.226314Z","caller":"traceutil/trace.go:171","msg":"trace[1464150687] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:0; response_revision:979; }","duration":"19.840716594s","start":"2026-01-28T06:22:36.385576Z","end":"2026-01-28T06:22:56.226293Z","steps":["trace[1464150687] 'agreement among raft nodes before linearized reading' (duration: 19.840442918s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.226448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:36.385559Z","time spent":"19.840877076s","remote":"127.0.0.1:59970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-01-28T06:22:56.234328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"27.465969335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-01-28T06:22:56.234443Z","caller":"traceutil/trace.go:171","msg":"trace[1226756713] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:979; }","duration":"27.466093167s","start":"2026-01-28T06:22:28.768339Z","end":"2026-01-28T06:22:56.234432Z","steps":["trace[1226756713] 'agreement among raft nodes before linearized reading' (duration: 27.465926544s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.234517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:28.768336Z","time spent":"27.466173828s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1559,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-01-28T06:22:56.234676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"22.495604294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.234759Z","caller":"traceutil/trace.go:171","msg":"trace[1691885170] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:979; }","duration":"22.495733366s","start":"2026-01-28T06:22:33.739018Z","end":"2026-01-28T06:22:56.234751Z","steps":["trace[1691885170] 'agreement among raft nodes before linearized reading' (duration: 22.495623444s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.234848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:33.738979Z","time spent":"22.495860329s","remote":"127.0.0.1:32980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.234998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"22.684142044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.23508Z","caller":"traceutil/trace.go:171","msg":"trace[1043740680] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:979; }","duration":"22.684249165s","start":"2026-01-28T06:22:33.550819Z","end":"2026-01-28T06:22:56.235068Z","steps":["trace[1043740680] 'agreement among raft nodes before linearized reading' (duration: 22.684157053s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.235136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:33.550801Z","time spent":"22.684328327s","remote":"127.0.0.1:60250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.23527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"24.97410513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T06:22:56.235531Z","caller":"traceutil/trace.go:171","msg":"trace[2118432868] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:979; }","duration":"24.974399015s","start":"2026-01-28T06:22:31.261126Z","end":"2026-01-28T06:22:56.235525Z","steps":["trace[2118432868] 'agreement among raft nodes before linearized reading' (duration: 24.974123519s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.235566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:31.261104Z","time spent":"24.974455056s","remote":"127.0.0.1:54880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-01-28T06:22:56.235753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"26.847560914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-01-28T06:22:56.235809Z","caller":"traceutil/trace.go:171","msg":"trace[1011696679] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:979; }","duration":"26.847640076s","start":"2026-01-28T06:22:29.388163Z","end":"2026-01-28T06:22:56.235803Z","steps":["trace[1011696679] 'agreement among raft nodes before linearized reading' (duration: 26.847541644s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.235862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:29.388153Z","time spent":"26.847681917s","remote":"127.0.0.1:60138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1559,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-01-28T06:22:56.236008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"27.379894667s","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-01-28T06:22:56.236068Z","caller":"traceutil/trace.go:171","msg":"trace[103347924] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:979; }","duration":"27.379988899s","start":"2026-01-28T06:22:28.856073Z","end":"2026-01-28T06:22:56.236062Z","steps":["trace[103347924] 'agreement among raft nodes before linearized reading' (duration: 27.379877827s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:22:56.236098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T06:22:28.856058Z","time spent":"27.38003444s","remote":"127.0.0.1:60106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1488,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-01-28T06:23:08.263013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.245192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:7008"} {"level":"info","ts":"2026-01-28T06:23:08.263102Z","caller":"traceutil/trace.go:171","msg":"trace[1729893240] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1061; }","duration":"158.359873ms","start":"2026-01-28T06:23:08.104721Z","end":"2026-01-28T06:23:08.263081Z","steps":["trace[1729893240] 'range keys from in-memory index tree' (duration: 158.088019ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:23:16.943144Z","caller":"traceutil/trace.go:171","msg":"trace[110189597] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"106.933114ms","start":"2026-01-28T06:23:16.836188Z","end":"2026-01-28T06:23:16.943122Z","steps":["trace[110189597] 'process raft request' (duration: 68.274662ms)","trace[110189597] 'compare' (duration: 38.53578ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:23:33.730696Z","caller":"traceutil/trace.go:171","msg":"trace[739358513] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"103.640128ms","start":"2026-01-28T06:23:33.627025Z","end":"2026-01-28T06:23:33.730665Z","steps":["trace[739358513] 'process raft request' (duration: 18.510877ms)","trace[739358513] 'compare' (duration: 84.686075ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T06:23:33.948271Z","caller":"traceutil/trace.go:171","msg":"trace[1926871608] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1197; }","duration":"139.065798ms","start":"2026-01-28T06:23:33.80918Z","end":"2026-01-28T06:23:33.948246Z","steps":["trace[1926871608] 'read index received' (duration: 110.916161ms)","trace[1926871608] 'applied index is now lower than readState.Index' (duration: 28.148717ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T06:23:33.948763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.563646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-01-28T06:23:33.948966Z","caller":"traceutil/trace.go:171","msg":"trace[1198283054] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"139.82584ms","start":"2026-01-28T06:23:33.809127Z","end":"2026-01-28T06:23:33.948953Z","steps":["trace[1198283054] 'agreement among raft nodes before linearized reading' (duration: 139.498625ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T06:23:33.949185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.308434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:kubeadm-certs\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-01-28T06:23:33.949214Z","caller":"traceutil/trace.go:171","msg":"trace[1102029639] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:kubeadm-certs; range_end:; response_count:1; response_revision:1139; }","duration":"123.375155ms","start":"2026-01-28T06:23:33.825832Z","end":"2026-01-28T06:23:33.949207Z","steps":["trace[1102029639] 'agreement among raft nodes before linearized reading' (duration: 123.325265ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T06:23:33.948826Z","caller":"traceutil/trace.go:171","msg":"trace[989084374] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"159.715059ms","start":"2026-01-28T06:23:33.789092Z","end":"2026-01-28T06:23:33.948808Z","steps":["trace[989084374] 'process raft request' (duration: 131.064104ms)","trace[989084374] 'compare' (duration: 28.008435ms)"],"step_count":2}