{"level":"warn","ts":"2026-02-18T22:52:32.417598Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:52:32.417865Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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://162.253.55.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-18T22:52:32.418087Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-18T22:52:32.418103Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-18T22:52:32.419192Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T22:52:32.420411Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-02-18T22:52:32.421307Z","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://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-18T22:52:32.429276Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.650651ms"} {"level":"info","ts":"2026-02-18T22:52:32.437782Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-02-18T22:52:32.437874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:52:32.437898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-02-18T22:52:32.437938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:52:32.437947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-02-18T22:52:32.438005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-02-18T22:52:32.452062Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:52:32.456438Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:52:32.460517Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-18T22:52:32.464474Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:52:32.465305Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:52:32.465521Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T22:52:32.465563Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T22:52:32.465621Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-18T22:52:32.466028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-02-18T22:52:32.467875Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-02-18T22:52:32.473803Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-18T22:52:32.47432Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:52:32.474343Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:52:32.475348Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-18T22:52:32.475364Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-02-18T22:52:33.139144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:52:33.139194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:52:33.139212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-02-18T22:52:33.139224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:52:33.13923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-18T22:52:33.139238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-02-18T22:52:33.139248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-02-18T22:52:33.140187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:52:33.140444Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:52:33.140506Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:52:33.141079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:52:33.143584Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:52:33.143624Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:52:33.145601Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T22:52:33.156819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-02-18T22:52:33.157117Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:52:33.157174Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:52:33.157191Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-18T22:52:49.091767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058176487569752,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:52:49.290836Z","caller":"traceutil/trace.go:171","msg":"trace[1084620246] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"867.851213ms","start":"2026-02-18T22:52:48.422967Z","end":"2026-02-18T22:52:49.290818Z","steps":["trace[1084620246] 'process raft request' (duration: 867.74706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:52:49.291411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:52:48.422956Z","time spent":"867.989856ms","remote":"127.0.0.1:48150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:52:49.320368Z","caller":"traceutil/trace.go:171","msg":"trace[2134646521] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"728.811368ms","start":"2026-02-18T22:52:48.591537Z","end":"2026-02-18T22:52:49.320348Z","steps":["trace[2134646521] 'read index received' (duration: 701.72013ms)","trace[2134646521] 'applied index is now lower than readState.Index' (duration: 27.090288ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:52:49.320464Z","caller":"traceutil/trace.go:171","msg":"trace[888275502] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"593.904562ms","start":"2026-02-18T22:52:48.726549Z","end":"2026-02-18T22:52:49.320453Z","steps":["trace[888275502] 'process raft request' (duration: 593.697637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:52:49.320664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:52:48.726527Z","time spent":"594.075216ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:52:49.320906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.366711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-18T22:52:49.320961Z","caller":"traceutil/trace.go:171","msg":"trace[458258499] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:375; }","duration":"729.443023ms","start":"2026-02-18T22:52:48.591508Z","end":"2026-02-18T22:52:49.320951Z","steps":["trace[458258499] 'agreement among raft nodes before linearized reading' (duration: 729.339611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:52:49.320989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:52:48.591494Z","time spent":"729.488864ms","remote":"127.0.0.1:48138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-18T22:52:49.321274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.812546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:52:49.321313Z","caller":"traceutil/trace.go:171","msg":"trace[1830110111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"546.886338ms","start":"2026-02-18T22:52:48.774418Z","end":"2026-02-18T22:52:49.321304Z","steps":["trace[1830110111] 'agreement among raft nodes before linearized reading' (duration: 546.830696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:52:49.321341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:52:48.774337Z","time spent":"546.99798ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:52:49.321837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.092182ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:52:49.321877Z","caller":"traceutil/trace.go:171","msg":"trace[1616714735] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:375; }","duration":"230.131023ms","start":"2026-02-18T22:52:49.091735Z","end":"2026-02-18T22:52:49.321866Z","steps":["trace[1616714735] 'agreement among raft nodes before linearized reading' (duration: 230.047071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:52:49.322246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.226451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:52:49.322288Z","caller":"traceutil/trace.go:171","msg":"trace[135221095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"418.270472ms","start":"2026-02-18T22:52:48.904007Z","end":"2026-02-18T22:52:49.322277Z","steps":["trace[135221095] 'agreement among raft nodes before linearized reading' (duration: 418.19723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:52:49.322316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:52:48.903968Z","time spent":"418.341434ms","remote":"127.0.0.1:48220","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-02-18T22:52:49.322594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.154317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:52:49.322661Z","caller":"traceutil/trace.go:171","msg":"trace[1986697049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"443.25757ms","start":"2026-02-18T22:52:48.879394Z","end":"2026-02-18T22:52:49.322651Z","steps":["trace[1986697049] 'agreement among raft nodes before linearized reading' (duration: 443.134347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:52:49.322694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:52:48.879377Z","time spent":"443.308431ms","remote":"127.0.0.1:48220","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-02-18T22:53:07.08175Z","caller":"traceutil/trace.go:171","msg":"trace[771001005] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"312.319049ms","start":"2026-02-18T22:53:06.769415Z","end":"2026-02-18T22:53:07.081734Z","steps":["trace[771001005] 'read index received' (duration: 312.227447ms)","trace[771001005] 'applied index is now lower than readState.Index' (duration: 90.962µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:07.081827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.402241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:07.081842Z","caller":"traceutil/trace.go:171","msg":"trace[1454931875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"312.456841ms","start":"2026-02-18T22:53:06.769381Z","end":"2026-02-18T22:53:07.081837Z","steps":["trace[1454931875] 'agreement among raft nodes before linearized reading' (duration: 312.4117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:07.081859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:06.769366Z","time spent":"312.489253ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:07.082019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:06.70082Z","time spent":"381.199076ms","remote":"127.0.0.1:47980","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:53:08.407679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.056376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:08.407859Z","caller":"traceutil/trace.go:171","msg":"trace[1602382946] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:501; }","duration":"807.10156ms","start":"2026-02-18T22:53:07.600729Z","end":"2026-02-18T22:53:08.407831Z","steps":["trace[1602382946] 'read index received' (duration: 43.994281ms)","trace[1602382946] 'applied index is now lower than readState.Index' (duration: 763.106448ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:08.407974Z","caller":"traceutil/trace.go:171","msg":"trace[769600069] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"833.576823ms","start":"2026-02-18T22:53:07.574385Z","end":"2026-02-18T22:53:08.407961Z","steps":["trace[769600069] 'process raft request' (duration: 833.394178ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:08.407967Z","caller":"traceutil/trace.go:171","msg":"trace[1806203878] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"834.973786ms","start":"2026-02-18T22:53:07.57297Z","end":"2026-02-18T22:53:08.407944Z","steps":["trace[1806203878] 'process raft request' (duration: 117.467079ms)","trace[1806203878] 'compare' (duration: 716.949354ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:08.40804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:07.57437Z","time spent":"833.635725ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:08.408071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:07.572965Z","time spent":"835.048558ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:08.408172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.937398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:08.408194Z","caller":"traceutil/trace.go:171","msg":"trace[39013890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"635.987498ms","start":"2026-02-18T22:53:07.772198Z","end":"2026-02-18T22:53:08.408186Z","steps":["trace[39013890] 'agreement among raft nodes before linearized reading' (duration: 635.954787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:08.408211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:07.772178Z","time spent":"636.0296ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:08.408339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.616423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:53:08.408358Z","caller":"traceutil/trace.go:171","msg":"trace[1193951833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"807.664273ms","start":"2026-02-18T22:53:07.600687Z","end":"2026-02-18T22:53:08.408352Z","steps":["trace[1193951833] 'agreement among raft nodes before linearized reading' (duration: 807.631012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:08.408385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:07.600673Z","time spent":"807.701325ms","remote":"127.0.0.1:48220","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-02-18T22:53:08.882782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.924895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:08.882866Z","caller":"traceutil/trace.go:171","msg":"trace[1755700546] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"110.202146ms","start":"2026-02-18T22:53:08.77265Z","end":"2026-02-18T22:53:08.882852Z","steps":["trace[1755700546] 'read index received' (duration: 22.441µs)","trace[1755700546] 'applied index is now lower than readState.Index' (duration: 110.178704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:08.882941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.286467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:08.882965Z","caller":"traceutil/trace.go:171","msg":"trace[915219226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"110.337239ms","start":"2026-02-18T22:53:08.77262Z","end":"2026-02-18T22:53:08.882957Z","steps":["trace[915219226] 'agreement among raft nodes before linearized reading' (duration: 110.272617ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:08.883256Z","caller":"traceutil/trace.go:171","msg":"trace[1607637342] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"470.523592ms","start":"2026-02-18T22:53:08.41272Z","end":"2026-02-18T22:53:08.883244Z","steps":["trace[1607637342] 'process raft request' (duration: 187.067214ms)","trace[1607637342] 'compare' (duration: 282.831713ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:08.883313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:08.412709Z","time spent":"470.572913ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:09.899905Z","caller":"traceutil/trace.go:171","msg":"trace[1368668111] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"252.239271ms","start":"2026-02-18T22:53:09.647652Z","end":"2026-02-18T22:53:09.899891Z","steps":["trace[1368668111] 'read index received' (duration: 252.127488ms)","trace[1368668111] 'applied index is now lower than readState.Index' (duration: 111.173µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:09.900046Z","caller":"traceutil/trace.go:171","msg":"trace[812185846] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"319.473929ms","start":"2026-02-18T22:53:09.580565Z","end":"2026-02-18T22:53:09.900039Z","steps":["trace[812185846] 'process raft request' (duration: 319.255223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:09.900125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:09.58055Z","time spent":"319.525121ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:09.900234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.583149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:53:09.90025Z","caller":"traceutil/trace.go:171","msg":"trace[1091202165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"252.59899ms","start":"2026-02-18T22:53:09.647646Z","end":"2026-02-18T22:53:09.900245Z","steps":["trace[1091202165] 'agreement among raft nodes before linearized reading' (duration: 252.567749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:09.900319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.728687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:09.90033Z","caller":"traceutil/trace.go:171","msg":"trace[479596978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"120.778638ms","start":"2026-02-18T22:53:09.779548Z","end":"2026-02-18T22:53:09.900327Z","steps":["trace[479596978] 'agreement among raft nodes before linearized reading' (duration: 120.759317ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:14.371093Z","caller":"traceutil/trace.go:171","msg":"trace[7440398] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"443.793202ms","start":"2026-02-18T22:53:13.927271Z","end":"2026-02-18T22:53:14.371065Z","steps":["trace[7440398] 'read index received' (duration: 443.581967ms)","trace[7440398] 'applied index is now lower than readState.Index' (duration: 210.525µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:14.371355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.062698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:53:14.371354Z","caller":"traceutil/trace.go:171","msg":"trace[1976949816] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"805.640993ms","start":"2026-02-18T22:53:13.565689Z","end":"2026-02-18T22:53:14.37133Z","steps":["trace[1976949816] 'process raft request' (duration: 805.238573ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:14.371398Z","caller":"traceutil/trace.go:171","msg":"trace[1273937528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"444.1554ms","start":"2026-02-18T22:53:13.927236Z","end":"2026-02-18T22:53:14.371391Z","steps":["trace[1273937528] 'agreement among raft nodes before linearized reading' (duration: 443.994017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:14.371472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:13.927208Z","time spent":"444.256652ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:53:14.371556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:13.565663Z","time spent":"805.752476ms","remote":"127.0.0.1:48150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:15.109562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.533751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:15.109647Z","caller":"traceutil/trace.go:171","msg":"trace[1751920220] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"677.773936ms","start":"2026-02-18T22:53:14.431858Z","end":"2026-02-18T22:53:15.109632Z","steps":["trace[1751920220] 'read index received' (duration: 412.036291ms)","trace[1751920220] 'applied index is now lower than readState.Index' (duration: 265.736335ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:15.110262Z","caller":"traceutil/trace.go:171","msg":"trace[983670686] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"733.065879ms","start":"2026-02-18T22:53:14.377169Z","end":"2026-02-18T22:53:15.110234Z","steps":["trace[983670686] 'process raft request' (duration: 466.794811ms)","trace[983670686] 'compare' (duration: 265.165862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:15.110345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:14.376965Z","time spent":"733.332896ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:15.110823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.960324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:53:15.110871Z","caller":"traceutil/trace.go:171","msg":"trace[2145340192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"679.030616ms","start":"2026-02-18T22:53:14.431818Z","end":"2026-02-18T22:53:15.110848Z","steps":["trace[2145340192] 'agreement among raft nodes before linearized reading' (duration: 678.905333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:15.110903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:14.4318Z","time spent":"679.095188ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:53:15.124475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.70466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:53:15.124512Z","caller":"traceutil/trace.go:171","msg":"trace[1362720736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"691.7644ms","start":"2026-02-18T22:53:14.432739Z","end":"2026-02-18T22:53:15.124504Z","steps":["trace[1362720736] 'agreement among raft nodes before linearized reading' (duration: 691.690359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:15.124529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:14.432731Z","time spent":"691.793261ms","remote":"127.0.0.1:48220","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-02-18T22:53:15.124605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.490468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:53:15.124618Z","caller":"traceutil/trace.go:171","msg":"trace[18672915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"216.536169ms","start":"2026-02-18T22:53:14.908078Z","end":"2026-02-18T22:53:15.124614Z","steps":["trace[18672915] 'agreement among raft nodes before linearized reading' (duration: 216.500778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:15.124675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.011527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:15.124687Z","caller":"traceutil/trace.go:171","msg":"trace[1756200585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:502; }","duration":"354.024077ms","start":"2026-02-18T22:53:14.77066Z","end":"2026-02-18T22:53:15.124684Z","steps":["trace[1756200585] 'agreement among raft nodes before linearized reading' (duration: 354.005376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:15.124698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:14.770604Z","time spent":"354.091188ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:53:17.293421Z","caller":"traceutil/trace.go:171","msg":"trace[2093815148] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"166.203924ms","start":"2026-02-18T22:53:17.127207Z","end":"2026-02-18T22:53:17.293411Z","steps":["trace[2093815148] 'process raft request' (duration: 165.877806ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:17.293478Z","caller":"traceutil/trace.go:171","msg":"trace[1896024040] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"162.24097ms","start":"2026-02-18T22:53:17.130949Z","end":"2026-02-18T22:53:17.29319Z","steps":["trace[1896024040] 'read index received' (duration: 162.065585ms)","trace[1896024040] 'applied index is now lower than readState.Index' (duration: 174.735µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:17.293639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.582258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:53:17.293661Z","caller":"traceutil/trace.go:171","msg":"trace[1348347182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"162.723491ms","start":"2026-02-18T22:53:17.130931Z","end":"2026-02-18T22:53:17.293654Z","steps":["trace[1348347182] 'agreement among raft nodes before linearized reading' (duration: 162.577917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:17.294311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.876107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:53:17.294335Z","caller":"traceutil/trace.go:171","msg":"trace[1396023775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"155.915128ms","start":"2026-02-18T22:53:17.138413Z","end":"2026-02-18T22:53:17.294328Z","steps":["trace[1396023775] 'agreement among raft nodes before linearized reading' (duration: 155.371985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:17.294497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.274783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:53:17.294517Z","caller":"traceutil/trace.go:171","msg":"trace[967675378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"155.304683ms","start":"2026-02-18T22:53:17.139207Z","end":"2026-02-18T22:53:17.294511Z","steps":["trace[967675378] 'agreement among raft nodes before linearized reading' (duration: 155.268112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:25.899783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058176487570246,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:53:26.353413Z","caller":"traceutil/trace.go:171","msg":"trace[81555638] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"955.258754ms","start":"2026-02-18T22:53:25.398139Z","end":"2026-02-18T22:53:26.353398Z","steps":["trace[81555638] 'process raft request' (duration: 955.134901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.353513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.398123Z","time spent":"955.335486ms","remote":"127.0.0.1:48220","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-02-18T22:53:26.804038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.543688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:26.80411Z","caller":"traceutil/trace.go:171","msg":"trace[1158815367] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"1.404384874s","start":"2026-02-18T22:53:25.399716Z","end":"2026-02-18T22:53:26.804101Z","steps":["trace[1158815367] 'read index received' (duration: 954.139227ms)","trace[1158815367] 'applied index is now lower than readState.Index' (duration: 450.244987ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:26.804311Z","caller":"traceutil/trace.go:171","msg":"trace[1022187876] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.062580471s","start":"2026-02-18T22:53:25.741723Z","end":"2026-02-18T22:53:26.804303Z","steps":["trace[1022187876] 'process raft request' (duration: 937.732475ms)","trace[1022187876] 'compare' (duration: 124.435806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:26.804339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.741708Z","time spent":"1.062618261s","remote":"127.0.0.1:44900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1238,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:53:26.804515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404795213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:53:26.804531Z","caller":"traceutil/trace.go:171","msg":"trace[75760916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"1.404827975s","start":"2026-02-18T22:53:25.399697Z","end":"2026-02-18T22:53:26.804525Z","steps":["trace[75760916] 'agreement among raft nodes before linearized reading' (duration: 1.404747903s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.804545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.399692Z","time spent":"1.404850335s","remote":"127.0.0.1:48220","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-02-18T22:53:26.804652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401042204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:53:26.804666Z","caller":"traceutil/trace.go:171","msg":"trace[2041289500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"1.401065244s","start":"2026-02-18T22:53:25.403596Z","end":"2026-02-18T22:53:26.804662Z","steps":["trace[2041289500] 'agreement among raft nodes before linearized reading' (duration: 1.401027913s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.804679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.403591Z","time spent":"1.401083294s","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:53:26.804797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.748957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:53:26.80481Z","caller":"traceutil/trace.go:171","msg":"trace[1549591757] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:616; }","duration":"102.796079ms","start":"2026-02-18T22:53:26.70201Z","end":"2026-02-18T22:53:26.804806Z","steps":["trace[1549591757] 'agreement among raft nodes before linearized reading' (duration: 102.756318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.804905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.018392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4svsx\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-18T22:53:26.804917Z","caller":"traceutil/trace.go:171","msg":"trace[336436707] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4svsx; range_end:; response_count:1; response_revision:616; }","duration":"731.066923ms","start":"2026-02-18T22:53:26.073846Z","end":"2026-02-18T22:53:26.804913Z","steps":["trace[336436707] 'agreement among raft nodes before linearized reading' (duration: 731.020462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.80494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:26.073827Z","time spent":"731.110734ms","remote":"127.0.0.1:48150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-4svsx\" "} {"level":"warn","ts":"2026-02-18T22:53:26.805039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.201352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:53:26.805081Z","caller":"traceutil/trace.go:171","msg":"trace[947904316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"886.264714ms","start":"2026-02-18T22:53:25.918812Z","end":"2026-02-18T22:53:26.805076Z","steps":["trace[947904316] 'agreement among raft nodes before linearized reading' (duration: 886.200452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.805093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.9188Z","time spent":"886.289864ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:53:26.805175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033283539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:26.805187Z","caller":"traceutil/trace.go:171","msg":"trace[687818310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.033314721s","start":"2026-02-18T22:53:25.771869Z","end":"2026-02-18T22:53:26.805183Z","steps":["trace[687818310] 'agreement among raft nodes before linearized reading' (duration: 1.03329531s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.805207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.771856Z","time spent":"1.033346711s","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:26.805331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376955848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:53:26.805345Z","caller":"traceutil/trace.go:171","msg":"trace[453709536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.376968438s","start":"2026-02-18T22:53:25.428371Z","end":"2026-02-18T22:53:26.805339Z","steps":["trace[453709536] 'agreement among raft nodes before linearized reading' (duration: 1.376933547s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.805365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.428361Z","time spent":"1.377000319s","remote":"127.0.0.1:48220","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-02-18T22:53:26.805445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377088021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:53:26.805457Z","caller":"traceutil/trace.go:171","msg":"trace[1048181514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.377123592s","start":"2026-02-18T22:53:25.42833Z","end":"2026-02-18T22:53:26.805453Z","steps":["trace[1048181514] 'agreement among raft nodes before linearized reading' (duration: 1.377088131s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:26.805468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:25.428315Z","time spent":"1.377150482s","remote":"127.0.0.1:48220","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-02-18T22:53:28.96759Z","caller":"traceutil/trace.go:171","msg":"trace[1993193531] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"190.992356ms","start":"2026-02-18T22:53:28.776582Z","end":"2026-02-18T22:53:28.967574Z","steps":["trace[1993193531] 'read index received' (duration: 181.428727ms)","trace[1993193531] 'applied index is now lower than readState.Index' (duration: 9.563049ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:28.967657Z","caller":"traceutil/trace.go:171","msg":"trace[760029079] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"218.128935ms","start":"2026-02-18T22:53:28.749524Z","end":"2026-02-18T22:53:28.967653Z","steps":["trace[760029079] 'process raft request' (duration: 208.523775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:28.967827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.233892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:28.967857Z","caller":"traceutil/trace.go:171","msg":"trace[1523986703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"191.284614ms","start":"2026-02-18T22:53:28.776555Z","end":"2026-02-18T22:53:28.96784Z","steps":["trace[1523986703] 'agreement among raft nodes before linearized reading' (duration: 191.233682ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:31.323126Z","caller":"traceutil/trace.go:171","msg":"trace[1771628087] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"251.362971ms","start":"2026-02-18T22:53:31.071743Z","end":"2026-02-18T22:53:31.323106Z","steps":["trace[1771628087] 'read index received' (duration: 251.261398ms)","trace[1771628087] 'applied index is now lower than readState.Index' (duration: 100.973µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:31.32321Z","caller":"traceutil/trace.go:171","msg":"trace[1892781214] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"324.91535ms","start":"2026-02-18T22:53:30.99829Z","end":"2026-02-18T22:53:31.323205Z","steps":["trace[1892781214] 'process raft request' (duration: 324.710535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:31.323289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:30.998269Z","time spent":"324.956451ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:31.323419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.676578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:53:31.323436Z","caller":"traceutil/trace.go:171","msg":"trace[822857401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"251.724259ms","start":"2026-02-18T22:53:31.071705Z","end":"2026-02-18T22:53:31.32343Z","steps":["trace[822857401] 'agreement among raft nodes before linearized reading' (duration: 251.653588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:31.323503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.525887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:53:31.323514Z","caller":"traceutil/trace.go:171","msg":"trace[249262189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"249.559817ms","start":"2026-02-18T22:53:31.073951Z","end":"2026-02-18T22:53:31.323511Z","steps":["trace[249262189] 'agreement among raft nodes before linearized reading' (duration: 249.523287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:31.323719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.927999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:53:31.323733Z","caller":"traceutil/trace.go:171","msg":"trace[1046998661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"247.9549ms","start":"2026-02-18T22:53:31.075774Z","end":"2026-02-18T22:53:31.323729Z","steps":["trace[1046998661] 'agreement among raft nodes before linearized reading' (duration: 247.911629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:31.323709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.876448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:53:31.323775Z","caller":"traceutil/trace.go:171","msg":"trace[1521901054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"247.958139ms","start":"2026-02-18T22:53:31.0758Z","end":"2026-02-18T22:53:31.323758Z","steps":["trace[1521901054] 'agreement among raft nodes before linearized reading' (duration: 247.789665ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:31.870345Z","caller":"traceutil/trace.go:171","msg":"trace[1695427924] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"179.188754ms","start":"2026-02-18T22:53:31.691141Z","end":"2026-02-18T22:53:31.870329Z","steps":["trace[1695427924] 'process raft request' (duration: 178.910888ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:32.620519Z","caller":"traceutil/trace.go:171","msg":"trace[1857272456] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"100.435582ms","start":"2026-02-18T22:53:32.520066Z","end":"2026-02-18T22:53:32.620502Z","steps":["trace[1857272456] 'read index received' (duration: 95.395791ms)","trace[1857272456] 'applied index is now lower than readState.Index' (duration: 5.039271ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:32.620687Z","caller":"traceutil/trace.go:171","msg":"trace[1157319896] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"107.705945ms","start":"2026-02-18T22:53:32.512951Z","end":"2026-02-18T22:53:32.620657Z","steps":["trace[1157319896] 'process raft request' (duration: 107.46955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:32.620722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.641006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-bc6kd\" ","response":"range_response_count:1 size:5026"} {"level":"info","ts":"2026-02-18T22:53:32.620747Z","caller":"traceutil/trace.go:171","msg":"trace[337217156] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-bc6kd; range_end:; response_count:1; response_revision:681; }","duration":"100.699398ms","start":"2026-02-18T22:53:32.520041Z","end":"2026-02-18T22:53:32.62074Z","steps":["trace[337217156] 'agreement among raft nodes before linearized reading' (duration: 100.571805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:37.68666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.061587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:53:37.686738Z","caller":"traceutil/trace.go:171","msg":"trace[756926548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:698; }","duration":"344.18258ms","start":"2026-02-18T22:53:37.342528Z","end":"2026-02-18T22:53:37.68671Z","steps":["trace[756926548] 'range keys from in-memory index tree' (duration: 343.931884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:37.686776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.342508Z","time spent":"344.251372ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:53:37.686781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.904163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:53:37.686837Z","caller":"traceutil/trace.go:171","msg":"trace[1895712487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:698; }","duration":"312.968805ms","start":"2026-02-18T22:53:37.373855Z","end":"2026-02-18T22:53:37.686824Z","steps":["trace[1895712487] 'range keys from in-memory index tree' (duration: 312.753449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:37.686868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.373811Z","time spent":"313.046976ms","remote":"127.0.0.1:48220","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-02-18T22:53:37.686965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.966558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:53:37.68698Z","caller":"traceutil/trace.go:171","msg":"trace[229568459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:698; }","duration":"309.022399ms","start":"2026-02-18T22:53:37.377952Z","end":"2026-02-18T22:53:37.686974Z","steps":["trace[229568459] 'range keys from in-memory index tree' (duration: 308.901436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:37.686995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.37794Z","time spent":"309.051361ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:53:37.687039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.832622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:37.687056Z","caller":"traceutil/trace.go:171","msg":"trace[230301597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"913.895984ms","start":"2026-02-18T22:53:36.773155Z","end":"2026-02-18T22:53:37.687051Z","steps":["trace[230301597] 'range keys from in-memory index tree' (duration: 913.786861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:37.687075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:36.773142Z","time spent":"913.930195ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:37.687157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.179142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:53:37.687181Z","caller":"traceutil/trace.go:171","msg":"trace[212755234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:698; }","duration":"301.214153ms","start":"2026-02-18T22:53:37.385957Z","end":"2026-02-18T22:53:37.687171Z","steps":["trace[212755234] 'range keys from in-memory index tree' (duration: 301.07529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:37.687197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.385949Z","time spent":"301.243173ms","remote":"127.0.0.1:48220","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-02-18T22:53:37.687224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.976936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:53:37.68724Z","caller":"traceutil/trace.go:171","msg":"trace[143746542] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:698; }","duration":"546.017786ms","start":"2026-02-18T22:53:37.141217Z","end":"2026-02-18T22:53:37.687235Z","steps":["trace[143746542] 'count revisions from in-memory index tree' (duration: 545.932135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:37.687266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.141204Z","time spent":"546.057808ms","remote":"127.0.0.1:48150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:53:37.687303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.12784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:53:37.68732Z","caller":"traceutil/trace.go:171","msg":"trace[1196287936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:698; }","duration":"237.167771ms","start":"2026-02-18T22:53:37.450147Z","end":"2026-02-18T22:53:37.687315Z","steps":["trace[1196287936] 'range keys from in-memory index tree' (duration: 237.038858ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:38.181627Z","caller":"traceutil/trace.go:171","msg":"trace[835371723] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:724; }","duration":"402.473775ms","start":"2026-02-18T22:53:37.779138Z","end":"2026-02-18T22:53:38.181612Z","steps":["trace[835371723] 'read index received' (duration: 402.28479ms)","trace[835371723] 'applied index is now lower than readState.Index' (duration: 188.495µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:38.181722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.565247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:38.181738Z","caller":"traceutil/trace.go:171","msg":"trace[307217997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"402.624138ms","start":"2026-02-18T22:53:37.77911Z","end":"2026-02-18T22:53:38.181734Z","steps":["trace[307217997] 'agreement among raft nodes before linearized reading' (duration: 402.558576ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:38.181736Z","caller":"traceutil/trace.go:171","msg":"trace[1785495163] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"485.683905ms","start":"2026-02-18T22:53:37.696042Z","end":"2026-02-18T22:53:38.181726Z","steps":["trace[1785495163] 'process raft request' (duration: 485.517651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:38.181756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.779097Z","time spent":"402.654979ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:38.181797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.696036Z","time spent":"485.722835ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:38.181929Z","caller":"traceutil/trace.go:171","msg":"trace[369296510] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"485.859929ms","start":"2026-02-18T22:53:37.696064Z","end":"2026-02-18T22:53:38.181924Z","steps":["trace[369296510] 'process raft request' (duration: 485.518591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:38.181957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.696047Z","time spent":"485.89384ms","remote":"127.0.0.1:48220","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-02-18T22:53:38.181981Z","caller":"traceutil/trace.go:171","msg":"trace[215735993] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"487.99405ms","start":"2026-02-18T22:53:37.693981Z","end":"2026-02-18T22:53:38.181976Z","steps":["trace[215735993] 'process raft request' (duration: 487.481917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:38.182008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.693975Z","time spent":"488.016761ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:38.182022Z","caller":"traceutil/trace.go:171","msg":"trace[745197954] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"485.930491ms","start":"2026-02-18T22:53:37.696086Z","end":"2026-02-18T22:53:38.182017Z","steps":["trace[745197954] 'process raft request' (duration: 485.510331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:38.182062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:37.696082Z","time spent":"485.949641ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:51.19532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.300221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:51.195416Z","caller":"traceutil/trace.go:171","msg":"trace[1468908116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"425.421264ms","start":"2026-02-18T22:53:50.769977Z","end":"2026-02-18T22:53:51.195399Z","steps":["trace[1468908116] 'range keys from in-memory index tree' (duration: 425.24429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:51.19547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:50.769956Z","time spent":"425.488916ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:53:52.474729Z","caller":"traceutil/trace.go:171","msg":"trace[79532852] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"176.782342ms","start":"2026-02-18T22:53:52.297929Z","end":"2026-02-18T22:53:52.474711Z","steps":["trace[79532852] 'process raft request' (duration: 176.67772ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:54.404699Z","caller":"traceutil/trace.go:171","msg":"trace[1286355894] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"138.202334ms","start":"2026-02-18T22:53:54.266477Z","end":"2026-02-18T22:53:54.404679Z","steps":["trace[1286355894] 'read index received' (duration: 138.02777ms)","trace[1286355894] 'applied index is now lower than readState.Index' (duration: 173.514µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:54.404801Z","caller":"traceutil/trace.go:171","msg":"trace[548652638] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"154.475666ms","start":"2026-02-18T22:53:54.250314Z","end":"2026-02-18T22:53:54.40479Z","steps":["trace[548652638] 'process raft request' (duration: 154.24094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:54.405012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.062761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:53:54.405101Z","caller":"traceutil/trace.go:171","msg":"trace[1172861447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"133.171204ms","start":"2026-02-18T22:53:54.271913Z","end":"2026-02-18T22:53:54.405084Z","steps":["trace[1172861447] 'agreement among raft nodes before linearized reading' (duration: 132.97826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:54.405264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.779668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:53:54.405291Z","caller":"traceutil/trace.go:171","msg":"trace[32678526] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"138.82058ms","start":"2026-02-18T22:53:54.266461Z","end":"2026-02-18T22:53:54.405281Z","steps":["trace[32678526] 'agreement among raft nodes before linearized reading' (duration: 138.733298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:54.801881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.950861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:54.802028Z","caller":"traceutil/trace.go:171","msg":"trace[1531319354] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:779; }","duration":"322.913328ms","start":"2026-02-18T22:53:54.479099Z","end":"2026-02-18T22:53:54.802013Z","steps":["trace[1531319354] 'read index received' (duration: 60.716561ms)","trace[1531319354] 'applied index is now lower than readState.Index' (duration: 262.195677ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:54.802254Z","caller":"traceutil/trace.go:171","msg":"trace[223554846] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"391.852886ms","start":"2026-02-18T22:53:54.410389Z","end":"2026-02-18T22:53:54.802242Z","steps":["trace[223554846] 'process raft request' (duration: 129.476605ms)","trace[223554846] 'compare' (duration: 261.850899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:54.802314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:54.410371Z","time spent":"391.910968ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:54.802503Z","caller":"traceutil/trace.go:171","msg":"trace[1547323076] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"381.405985ms","start":"2026-02-18T22:53:54.421083Z","end":"2026-02-18T22:53:54.802489Z","steps":["trace[1547323076] 'process raft request' (duration: 380.886273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:54.802554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:54.421059Z","time spent":"381.467687ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:54.802742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.643696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:53:54.803479Z","caller":"traceutil/trace.go:171","msg":"trace[58284609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"324.401704ms","start":"2026-02-18T22:53:54.479064Z","end":"2026-02-18T22:53:54.803465Z","steps":["trace[58284609] 'agreement among raft nodes before linearized reading' (duration: 323.589754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:54.80354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:54.479049Z","time spent":"324.474595ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:53:55.471184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.189651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:53:55.471295Z","caller":"traceutil/trace.go:171","msg":"trace[359092557] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"663.366848ms","start":"2026-02-18T22:53:54.807897Z","end":"2026-02-18T22:53:55.471264Z","steps":["trace[359092557] 'process raft request' (duration: 304.018223ms)","trace[359092557] 'compare' (duration: 358.71483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:55.471359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:54.807883Z","time spent":"663.44903ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:56.494065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.511945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:53:56.494141Z","caller":"traceutil/trace.go:171","msg":"trace[564248371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"671.624058ms","start":"2026-02-18T22:53:55.822502Z","end":"2026-02-18T22:53:56.494126Z","steps":["trace[564248371] 'range keys from in-memory index tree' (duration: 671.446913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:56.494181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:55.822488Z","time spent":"671.679939ms","remote":"127.0.0.1:48220","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-02-18T22:53:56.49544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.821569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:53:56.495479Z","caller":"traceutil/trace.go:171","msg":"trace[1878819566] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:753; }","duration":"409.898581ms","start":"2026-02-18T22:53:56.085569Z","end":"2026-02-18T22:53:56.495468Z","steps":["trace[1878819566] 'count revisions from in-memory index tree' (duration: 409.762628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:56.49551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:56.085552Z","time spent":"409.949642ms","remote":"127.0.0.1:48076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:53:56.904756Z","caller":"traceutil/trace.go:171","msg":"trace[912650986] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"401.997061ms","start":"2026-02-18T22:53:56.502734Z","end":"2026-02-18T22:53:56.904731Z","steps":["trace[912650986] 'process raft request' (duration: 401.867348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:56.905114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:56.502717Z","time spent":"402.273528ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:53:56.961625Z","caller":"traceutil/trace.go:171","msg":"trace[1911776562] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"257.798102ms","start":"2026-02-18T22:53:56.703803Z","end":"2026-02-18T22:53:56.961601Z","steps":["trace[1911776562] 'read index received' (duration: 200.936764ms)","trace[1911776562] 'applied index is now lower than readState.Index' (duration: 56.860408ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:56.962186Z","caller":"traceutil/trace.go:171","msg":"trace[1721016813] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"457.476105ms","start":"2026-02-18T22:53:56.50469Z","end":"2026-02-18T22:53:56.962166Z","steps":["trace[1721016813] 'process raft request' (duration: 456.82133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:56.962702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:56.504674Z","time spent":"457.923286ms","remote":"127.0.0.1:48220","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-02-18T22:53:56.963553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.750669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:53:56.964852Z","caller":"traceutil/trace.go:171","msg":"trace[554170068] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:755; }","duration":"261.090402ms","start":"2026-02-18T22:53:56.703746Z","end":"2026-02-18T22:53:56.964837Z","steps":["trace[554170068] 'agreement among raft nodes before linearized reading' (duration: 259.670357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:56.965161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.716942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:56.965622Z","caller":"traceutil/trace.go:171","msg":"trace[501403179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"192.213954ms","start":"2026-02-18T22:53:56.773395Z","end":"2026-02-18T22:53:56.965609Z","steps":["trace[501403179] 'agreement among raft nodes before linearized reading' (duration: 191.717702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:56.965213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.77231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:53:56.9664Z","caller":"traceutil/trace.go:171","msg":"trace[878789092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"154.951868ms","start":"2026-02-18T22:53:56.811434Z","end":"2026-02-18T22:53:56.966386Z","steps":["trace[878789092] 'agreement among raft nodes before linearized reading' (duration: 153.748219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:56.965248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.793474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:53:56.967037Z","caller":"traceutil/trace.go:171","msg":"trace[911598207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"156.607827ms","start":"2026-02-18T22:53:56.810415Z","end":"2026-02-18T22:53:56.967022Z","steps":["trace[911598207] 'agreement among raft nodes before linearized reading' (duration: 154.815764ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:57.165499Z","caller":"traceutil/trace.go:171","msg":"trace[1131898291] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"194.56537ms","start":"2026-02-18T22:53:56.970915Z","end":"2026-02-18T22:53:57.165481Z","steps":["trace[1131898291] 'read index received' (duration: 122.959088ms)","trace[1131898291] 'applied index is now lower than readState.Index' (duration: 71.605542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:53:57.165891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.95569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:53:57.1661Z","caller":"traceutil/trace.go:171","msg":"trace[1929237011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"195.185766ms","start":"2026-02-18T22:53:56.970901Z","end":"2026-02-18T22:53:57.166086Z","steps":["trace[1929237011] 'agreement among raft nodes before linearized reading' (duration: 194.891079ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:57.16729Z","caller":"traceutil/trace.go:171","msg":"trace[1970873014] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"196.456287ms","start":"2026-02-18T22:53:56.970819Z","end":"2026-02-18T22:53:57.167275Z","steps":["trace[1970873014] 'process raft request' (duration: 194.465739ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:57.167823Z","caller":"traceutil/trace.go:171","msg":"trace[559776419] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"195.734929ms","start":"2026-02-18T22:53:56.972027Z","end":"2026-02-18T22:53:57.167762Z","steps":["trace[559776419] 'process raft request' (duration: 193.419373ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:57.365291Z","caller":"traceutil/trace.go:171","msg":"trace[204649231] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"223.547848ms","start":"2026-02-18T22:53:57.141717Z","end":"2026-02-18T22:53:57.365265Z","steps":["trace[204649231] 'process raft request' (duration: 158.589245ms)","trace[204649231] 'compare' (duration: 64.261416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:09.232345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.324596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:09.232422Z","caller":"traceutil/trace.go:171","msg":"trace[1553017403] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"211.939559ms","start":"2026-02-18T22:54:09.020471Z","end":"2026-02-18T22:54:09.232411Z","steps":["trace[1553017403] 'process raft request' (duration: 56.383277ms)","trace[1553017403] 'compare' (duration: 154.997748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:09.741191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058176487570755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:10.032366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:09.58384Z","time spent":"448.4986ms","remote":"127.0.0.1:53202","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T22:54:10.032897Z","caller":"traceutil/trace.go:171","msg":"trace[503136262] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:876; }","duration":"792.437604ms","start":"2026-02-18T22:54:09.240434Z","end":"2026-02-18T22:54:10.032871Z","steps":["trace[503136262] 'read index received' (duration: 728.506986ms)","trace[503136262] 'applied index is now lower than readState.Index' (duration: 63.929518ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:10.033016Z","caller":"traceutil/trace.go:171","msg":"trace[1857591903] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"793.232263ms","start":"2026-02-18T22:54:09.23977Z","end":"2026-02-18T22:54:10.033002Z","steps":["trace[1857591903] 'process raft request' (duration: 729.222823ms)","trace[1857591903] 'compare' (duration: 63.299603ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:10.033151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:09.239761Z","time spent":"793.317155ms","remote":"127.0.0.1:48220","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-02-18T22:54:10.033428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.909724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:10.033492Z","caller":"traceutil/trace.go:171","msg":"trace[485661258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:843; }","duration":"465.014497ms","start":"2026-02-18T22:54:09.56845Z","end":"2026-02-18T22:54:10.033465Z","steps":["trace[485661258] 'agreement among raft nodes before linearized reading' (duration: 464.815702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:10.03354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:09.568433Z","time spent":"465.099278ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:54:10.033706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.147444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:54:10.033708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.267673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:54:10.033738Z","caller":"traceutil/trace.go:171","msg":"trace[1252323065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"264.200726ms","start":"2026-02-18T22:54:09.769527Z","end":"2026-02-18T22:54:10.033727Z","steps":["trace[1252323065] 'agreement among raft nodes before linearized reading' (duration: 264.149175ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:10.033752Z","caller":"traceutil/trace.go:171","msg":"trace[1861438664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:843; }","duration":"793.338775ms","start":"2026-02-18T22:54:09.240399Z","end":"2026-02-18T22:54:10.033737Z","steps":["trace[1861438664] 'agreement among raft nodes before linearized reading' (duration: 793.211312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:10.033786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:09.240387Z","time spent":"793.391906ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-18T22:54:17.009799Z","caller":"traceutil/trace.go:171","msg":"trace[816747603] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"151.355822ms","start":"2026-02-18T22:54:16.858424Z","end":"2026-02-18T22:54:17.00978Z","steps":["trace[816747603] 'read index received' (duration: 120.790036ms)","trace[816747603] 'applied index is now lower than readState.Index' (duration: 30.564906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:17.009914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.471884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:17.009932Z","caller":"traceutil/trace.go:171","msg":"trace[480083112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:862; }","duration":"151.528185ms","start":"2026-02-18T22:54:16.858398Z","end":"2026-02-18T22:54:17.009927Z","steps":["trace[480083112] 'agreement among raft nodes before linearized reading' (duration: 151.459013ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:17.009963Z","caller":"traceutil/trace.go:171","msg":"trace[145005320] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"151.563556ms","start":"2026-02-18T22:54:16.858374Z","end":"2026-02-18T22:54:17.009938Z","steps":["trace[145005320] 'process raft request' (duration: 120.873588ms)","trace[145005320] 'compare' (duration: 30.441392ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:17.378869Z","caller":"traceutil/trace.go:171","msg":"trace[16527528] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"116.443171ms","start":"2026-02-18T22:54:17.26241Z","end":"2026-02-18T22:54:17.378853Z","steps":["trace[16527528] 'process raft request' (duration: 116.336759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:18.011407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.309293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:54:18.011463Z","caller":"traceutil/trace.go:171","msg":"trace[600971177] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:865; }","duration":"137.405466ms","start":"2026-02-18T22:54:17.874045Z","end":"2026-02-18T22:54:18.01145Z","steps":["trace[600971177] 'count revisions from in-memory index tree' (duration: 137.253082ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:18.238179Z","caller":"traceutil/trace.go:171","msg":"trace[786749673] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"118.504561ms","start":"2026-02-18T22:54:18.119659Z","end":"2026-02-18T22:54:18.238163Z","steps":["trace[786749673] 'process raft request' (duration: 96.103012ms)","trace[786749673] 'compare' (duration: 22.167413ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:21.653925Z","caller":"traceutil/trace.go:171","msg":"trace[455010519] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"496.200267ms","start":"2026-02-18T22:54:21.157705Z","end":"2026-02-18T22:54:21.653905Z","steps":["trace[455010519] 'process raft request' (duration: 496.059634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:21.654065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:21.157696Z","time spent":"496.305989ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:21.723056Z","caller":"traceutil/trace.go:171","msg":"trace[1135503250] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"337.11053ms","start":"2026-02-18T22:54:21.38593Z","end":"2026-02-18T22:54:21.72304Z","steps":["trace[1135503250] 'read index received' (duration: 267.979216ms)","trace[1135503250] 'applied index is now lower than readState.Index' (duration: 69.130624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:21.723417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.342941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:54:21.723437Z","caller":"traceutil/trace.go:171","msg":"trace[1212555496] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:875; }","duration":"119.405703ms","start":"2026-02-18T22:54:21.604026Z","end":"2026-02-18T22:54:21.723432Z","steps":["trace[1212555496] 'agreement among raft nodes before linearized reading' (duration: 119.28492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:21.723394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.453199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:54:21.723498Z","caller":"traceutil/trace.go:171","msg":"trace[458842178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:875; }","duration":"337.587191ms","start":"2026-02-18T22:54:21.385904Z","end":"2026-02-18T22:54:21.723491Z","steps":["trace[458842178] 'agreement among raft nodes before linearized reading' (duration: 337.322895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:21.723511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:21.385891Z","time spent":"337.614662ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-18T22:54:21.723675Z","caller":"traceutil/trace.go:171","msg":"trace[1495416248] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"466.901493ms","start":"2026-02-18T22:54:21.256768Z","end":"2026-02-18T22:54:21.72367Z","steps":["trace[1495416248] 'process raft request' (duration: 465.358656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:21.723749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:21.256755Z","time spent":"466.932624ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:24.229886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058176487570858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:54:24.238534Z","caller":"traceutil/trace.go:171","msg":"trace[511737708] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"509.149798ms","start":"2026-02-18T22:54:23.729372Z","end":"2026-02-18T22:54:24.238522Z","steps":["trace[511737708] 'read index received' (duration: 509.006005ms)","trace[511737708] 'applied index is now lower than readState.Index' (duration: 143.213µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:24.238598Z","caller":"traceutil/trace.go:171","msg":"trace[1697465675] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"537.155082ms","start":"2026-02-18T22:54:23.701436Z","end":"2026-02-18T22:54:24.238591Z","steps":["trace[1697465675] 'process raft request' (duration: 536.992278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.23868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.70142Z","time spent":"537.194024ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:24.239032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.662451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:54:24.239055Z","caller":"traceutil/trace.go:171","msg":"trace[421902469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"509.717072ms","start":"2026-02-18T22:54:23.729331Z","end":"2026-02-18T22:54:24.239049Z","steps":["trace[421902469] 'agreement among raft nodes before linearized reading' (duration: 509.63237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.239086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.729321Z","time spent":"509.760133ms","remote":"127.0.0.1:48220","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-02-18T22:54:24.24002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.891048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.240045Z","caller":"traceutil/trace.go:171","msg":"trace[1252251678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"470.93746ms","start":"2026-02-18T22:54:23.769101Z","end":"2026-02-18T22:54:24.240038Z","steps":["trace[1252251678] 'agreement among raft nodes before linearized reading' (duration: 470.898619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:24.24102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:23.769089Z","time spent":"471.922833ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:24.4561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.111125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:24.456159Z","caller":"traceutil/trace.go:171","msg":"trace[1460489783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"210.196907ms","start":"2026-02-18T22:54:24.24595Z","end":"2026-02-18T22:54:24.456147Z","steps":["trace[1460489783] 'range keys from in-memory index tree' (duration: 209.966472ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:24.456521Z","caller":"traceutil/trace.go:171","msg":"trace[1894053419] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"210.484883ms","start":"2026-02-18T22:54:24.24603Z","end":"2026-02-18T22:54:24.456515Z","steps":["trace[1894053419] 'process raft request' (duration: 152.56967ms)","trace[1894053419] 'compare' (duration: 57.432882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.158235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.533207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:25.158317Z","caller":"traceutil/trace.go:171","msg":"trace[12535986] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:917; }","duration":"905.075663ms","start":"2026-02-18T22:54:24.253226Z","end":"2026-02-18T22:54:25.158301Z","steps":["trace[12535986] 'read index received' (duration: 145.351587ms)","trace[12535986] 'applied index is now lower than readState.Index' (duration: 759.723056ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:25.158501Z","caller":"traceutil/trace.go:171","msg":"trace[639780478] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"911.318824ms","start":"2026-02-18T22:54:24.24717Z","end":"2026-02-18T22:54:25.158489Z","steps":["trace[639780478] 'process raft request' (duration: 379.467979ms)","trace[639780478] 'compare' (duration: 531.183909ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:25.158557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.247149Z","time spent":"911.378085ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:25.158781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.556155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T22:54:25.158809Z","caller":"traceutil/trace.go:171","msg":"trace[65618599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"905.609746ms","start":"2026-02-18T22:54:24.25319Z","end":"2026-02-18T22:54:25.1588Z","steps":["trace[65618599] 'agreement among raft nodes before linearized reading' (duration: 905.510074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.158861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.253173Z","time spent":"905.681728ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:25.159037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.792317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:54:25.159064Z","caller":"traceutil/trace.go:171","msg":"trace[1688718669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:882; }","duration":"835.841608ms","start":"2026-02-18T22:54:24.323214Z","end":"2026-02-18T22:54:25.159055Z","steps":["trace[1688718669] 'agreement among raft nodes before linearized reading' (duration: 835.766896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:25.159088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:24.323201Z","time spent":"835.881328ms","remote":"127.0.0.1:48220","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-02-18T22:54:26.539348Z","caller":"traceutil/trace.go:171","msg":"trace[2043202655] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"292.982338ms","start":"2026-02-18T22:54:26.246347Z","end":"2026-02-18T22:54:26.53933Z","steps":["trace[2043202655] 'process raft request' (duration: 292.669221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.044034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.930869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T22:54:27.044146Z","caller":"traceutil/trace.go:171","msg":"trace[134745263] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:886; }","duration":"338.071813ms","start":"2026-02-18T22:54:26.706034Z","end":"2026-02-18T22:54:27.044106Z","steps":["trace[134745263] 'range keys from in-memory index tree' (duration: 337.756786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.044187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:26.706017Z","time spent":"338.160786ms","remote":"127.0.0.1:47980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.207\" "} {"level":"warn","ts":"2026-02-18T22:54:27.044246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.501378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:27.044296Z","caller":"traceutil/trace.go:171","msg":"trace[707442339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"270.59875ms","start":"2026-02-18T22:54:26.773683Z","end":"2026-02-18T22:54:27.044282Z","steps":["trace[707442339] 'range keys from in-memory index tree' (duration: 270.428265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.302033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.584086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-18T22:54:27.302098Z","caller":"traceutil/trace.go:171","msg":"trace[1822384018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:887; }","duration":"136.685608ms","start":"2026-02-18T22:54:27.165398Z","end":"2026-02-18T22:54:27.302084Z","steps":["trace[1822384018] 'range keys from in-memory index tree' (duration: 136.466643ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:28.581257Z","caller":"traceutil/trace.go:171","msg":"trace[1826150509] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"242.032222ms","start":"2026-02-18T22:54:28.339207Z","end":"2026-02-18T22:54:28.581239Z","steps":["trace[1826150509] 'read index received' (duration: 241.591972ms)","trace[1826150509] 'applied index is now lower than readState.Index' (duration: 439.12µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:28.581278Z","caller":"traceutil/trace.go:171","msg":"trace[1511589004] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"654.802402ms","start":"2026-02-18T22:54:27.926456Z","end":"2026-02-18T22:54:28.581259Z","steps":["trace[1511589004] 'process raft request' (duration: 654.362121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:28.581379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:27.92644Z","time spent":"654.883635ms","remote":"127.0.0.1:48220","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-02-18T22:54:28.581418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.192236ms","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-02-18T22:54:28.58145Z","caller":"traceutil/trace.go:171","msg":"trace[849258052] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:891; }","duration":"242.272178ms","start":"2026-02-18T22:54:28.339169Z","end":"2026-02-18T22:54:28.581441Z","steps":["trace[849258052] 'agreement among raft nodes before linearized reading' (duration: 242.183076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.233704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.787529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:29.233795Z","caller":"traceutil/trace.go:171","msg":"trace[86591853] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"647.471337ms","start":"2026-02-18T22:54:28.586306Z","end":"2026-02-18T22:54:29.233778Z","steps":["trace[86591853] 'process raft request' (duration: 540.541034ms)","trace[86591853] 'compare' (duration: 106.695357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:29.233851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.586295Z","time spent":"647.530158ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:29.325716Z","caller":"traceutil/trace.go:171","msg":"trace[1024231163] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:929; }","duration":"531.468075ms","start":"2026-02-18T22:54:28.794237Z","end":"2026-02-18T22:54:29.325705Z","steps":["trace[1024231163] 'read index received' (duration: 332.56346ms)","trace[1024231163] 'applied index is now lower than readState.Index' (duration: 198.903955ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:29.325852Z","caller":"traceutil/trace.go:171","msg":"trace[770289406] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"608.087369ms","start":"2026-02-18T22:54:28.717759Z","end":"2026-02-18T22:54:29.325847Z","steps":["trace[770289406] 'process raft request' (duration: 607.869044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.325917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.717751Z","time spent":"608.114879ms","remote":"127.0.0.1:48220","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-02-18T22:54:29.326013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.777003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:29.326028Z","caller":"traceutil/trace.go:171","msg":"trace[1769372696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"531.821314ms","start":"2026-02-18T22:54:28.794202Z","end":"2026-02-18T22:54:29.326023Z","steps":["trace[1769372696] 'agreement among raft nodes before linearized reading' (duration: 531.797653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.326039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.794186Z","time spent":"531.850984ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:54:29.326122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.585826ms","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-02-18T22:54:29.326136Z","caller":"traceutil/trace.go:171","msg":"trace[168161583] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:893; }","duration":"390.619588ms","start":"2026-02-18T22:54:28.935513Z","end":"2026-02-18T22:54:29.326132Z","steps":["trace[168161583] 'agreement among raft nodes before linearized reading' (duration: 390.590467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.326147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.935504Z","time spent":"390.640378ms","remote":"127.0.0.1:48060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:54:29.326436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.632023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:29.326452Z","caller":"traceutil/trace.go:171","msg":"trace[1183197032] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:893; }","duration":"337.661513ms","start":"2026-02-18T22:54:28.988786Z","end":"2026-02-18T22:54:29.326448Z","steps":["trace[1183197032] 'agreement among raft nodes before linearized reading' (duration: 337.631933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.326477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.988781Z","time spent":"337.679854ms","remote":"127.0.0.1:44892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:54:29.665451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.795166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:29.665562Z","caller":"traceutil/trace.go:171","msg":"trace[1468792226] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"333.873612ms","start":"2026-02-18T22:54:29.331678Z","end":"2026-02-18T22:54:29.665552Z","steps":["trace[1468792226] 'process raft request' (duration: 333.855212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.665596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.331661Z","time spent":"333.920964ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:29.665761Z","caller":"traceutil/trace.go:171","msg":"trace[2147389454] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"336.115336ms","start":"2026-02-18T22:54:29.329639Z","end":"2026-02-18T22:54:29.665755Z","steps":["trace[2147389454] 'process raft request' (duration: 210.004392ms)","trace[2147389454] 'compare' (duration: 125.571121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:29.665787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.329629Z","time spent":"336.143736ms","remote":"127.0.0.1:48220","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-02-18T22:54:29.665847Z","caller":"traceutil/trace.go:171","msg":"trace[1474615290] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"336.132726ms","start":"2026-02-18T22:54:29.329708Z","end":"2026-02-18T22:54:29.66584Z","steps":["trace[1474615290] 'read index received' (duration: 209.94732ms)","trace[1474615290] 'applied index is now lower than readState.Index' (duration: 126.184726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:29.665873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.163617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:29.665884Z","caller":"traceutil/trace.go:171","msg":"trace[178239742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"336.184328ms","start":"2026-02-18T22:54:29.329696Z","end":"2026-02-18T22:54:29.66588Z","steps":["trace[178239742] 'agreement among raft nodes before linearized reading' (duration: 336.162107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.665897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.329691Z","time spent":"336.203088ms","remote":"127.0.0.1:47944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:54:29.665968Z","caller":"traceutil/trace.go:171","msg":"trace[1921819011] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"335.152493ms","start":"2026-02-18T22:54:29.330811Z","end":"2026-02-18T22:54:29.665963Z","steps":["trace[1921819011] 'process raft request' (duration: 334.699802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.665993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.330804Z","time spent":"335.174103ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:31.472577Z","caller":"traceutil/trace.go:171","msg":"trace[1540671283] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"142.443707ms","start":"2026-02-18T22:54:31.330117Z","end":"2026-02-18T22:54:31.472561Z","steps":["trace[1540671283] 'read index received' (duration: 142.286983ms)","trace[1540671283] 'applied index is now lower than readState.Index' (duration: 156.054µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:31.472702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.57258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-18T22:54:31.472722Z","caller":"traceutil/trace.go:171","msg":"trace[1613468297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"142.637892ms","start":"2026-02-18T22:54:31.330078Z","end":"2026-02-18T22:54:31.472716Z","steps":["trace[1613468297] 'agreement among raft nodes before linearized reading' (duration: 142.542469ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:31.472794Z","caller":"traceutil/trace.go:171","msg":"trace[1940765977] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"229.590353ms","start":"2026-02-18T22:54:31.243175Z","end":"2026-02-18T22:54:31.472766Z","steps":["trace[1940765977] 'process raft request' (duration: 229.271635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:31.945762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.966768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:31.945918Z","caller":"traceutil/trace.go:171","msg":"trace[1258834194] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"107.439114ms","start":"2026-02-18T22:54:31.838464Z","end":"2026-02-18T22:54:31.945903Z","steps":["trace[1258834194] 'process raft request' (duration: 107.387853ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:31.9462Z","caller":"traceutil/trace.go:171","msg":"trace[1123836301] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"470.409627ms","start":"2026-02-18T22:54:31.475779Z","end":"2026-02-18T22:54:31.946189Z","steps":["trace[1123836301] 'process raft request' (duration: 65.916396ms)","trace[1123836301] 'compare' (duration: 403.788124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:31.946257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:31.475766Z","time spent":"470.458398ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:31.946372Z","caller":"traceutil/trace.go:171","msg":"trace[1398411162] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"276.44996ms","start":"2026-02-18T22:54:31.669912Z","end":"2026-02-18T22:54:31.946362Z","steps":["trace[1398411162] 'read index received' (duration: 49.231µs)","trace[1398411162] 'applied index is now lower than readState.Index' (duration: 276.399779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:31.947014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.090776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:31.947056Z","caller":"traceutil/trace.go:171","msg":"trace[1470472959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"277.136577ms","start":"2026-02-18T22:54:31.669908Z","end":"2026-02-18T22:54:31.947044Z","steps":["trace[1470472959] 'agreement among raft nodes before linearized reading' (duration: 276.979613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:31.947212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.871505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:31.947237Z","caller":"traceutil/trace.go:171","msg":"trace[1673685605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"171.896175ms","start":"2026-02-18T22:54:31.775333Z","end":"2026-02-18T22:54:31.947229Z","steps":["trace[1673685605] 'agreement among raft nodes before linearized reading' (duration: 171.858644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:31.947455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.456464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:54:31.947492Z","caller":"traceutil/trace.go:171","msg":"trace[578864473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:899; }","duration":"277.496805ms","start":"2026-02-18T22:54:31.669987Z","end":"2026-02-18T22:54:31.947484Z","steps":["trace[578864473] 'agreement among raft nodes before linearized reading' (duration: 277.382583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:31.947521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.549557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:54:31.947557Z","caller":"traceutil/trace.go:171","msg":"trace[989355807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"277.597458ms","start":"2026-02-18T22:54:31.669949Z","end":"2026-02-18T22:54:31.947546Z","steps":["trace[989355807] 'agreement among raft nodes before linearized reading' (duration: 277.498016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:32.462975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.773729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:32.463462Z","caller":"traceutil/trace.go:171","msg":"trace[1155048864] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"509.841715ms","start":"2026-02-18T22:54:31.95359Z","end":"2026-02-18T22:54:32.463432Z","steps":["trace[1155048864] 'process raft request' (duration: 402.504943ms)","trace[1155048864] 'compare' (duration: 106.488152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:32.463597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:31.953578Z","time spent":"509.947097ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:54:32.463929Z","caller":"traceutil/trace.go:171","msg":"trace[990453726] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"508.871842ms","start":"2026-02-18T22:54:31.955046Z","end":"2026-02-18T22:54:32.463918Z","steps":["trace[990453726] 'process raft request' (duration: 508.011501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:32.463991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:31.955038Z","time spent":"508.919143ms","remote":"127.0.0.1:48220","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-02-18T22:54:32.464188Z","caller":"traceutil/trace.go:171","msg":"trace[1526674722] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"475.450667ms","start":"2026-02-18T22:54:31.988728Z","end":"2026-02-18T22:54:32.464179Z","steps":["trace[1526674722] 'process raft request' (duration: 474.374161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:32.464267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:31.988713Z","time spent":"475.497639ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:32.945911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.749384ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-18T22:54:32.946017Z","caller":"traceutil/trace.go:171","msg":"trace[1423639241] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"324.721562ms","start":"2026-02-18T22:54:32.621264Z","end":"2026-02-18T22:54:32.945986Z","steps":["trace[1423639241] 'read index received' (duration: 36.361µs)","trace[1423639241] 'applied index is now lower than readState.Index' (duration: 324.683911ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:32.946687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.409729ms","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-02-18T22:54:32.946732Z","caller":"traceutil/trace.go:171","msg":"trace[388605594] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:902; }","duration":"325.4633ms","start":"2026-02-18T22:54:32.621258Z","end":"2026-02-18T22:54:32.946722Z","steps":["trace[388605594] 'agreement among raft nodes before linearized reading' (duration: 324.786353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:32.946768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:32.621203Z","time spent":"325.552862ms","remote":"127.0.0.1:48292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:54:34.096766Z","caller":"traceutil/trace.go:171","msg":"trace[582634561] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"141.768551ms","start":"2026-02-18T22:54:33.954976Z","end":"2026-02-18T22:54:34.096745Z","steps":["trace[582634561] 'process raft request' (duration: 141.660288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:34.972243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.128503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-18T22:54:34.972321Z","caller":"traceutil/trace.go:171","msg":"trace[1084473446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:912; }","duration":"503.270787ms","start":"2026-02-18T22:54:34.46903Z","end":"2026-02-18T22:54:34.972301Z","steps":["trace[1084473446] 'range keys from in-memory index tree' (duration: 502.97206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:34.972308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.310668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:54:34.97238Z","caller":"traceutil/trace.go:171","msg":"trace[86109751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:912; }","duration":"503.438021ms","start":"2026-02-18T22:54:34.468921Z","end":"2026-02-18T22:54:34.972359Z","steps":["trace[86109751] 'range keys from in-memory index tree' (duration: 503.028521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:34.97242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:34.468901Z","time spent":"503.510823ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:34.972363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:34.469025Z","time spent":"503.329619ms","remote":"127.0.0.1:48220","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-02-18T22:54:34.972685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.158705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:34.972715Z","caller":"traceutil/trace.go:171","msg":"trace[1447666235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"503.203435ms","start":"2026-02-18T22:54:34.469502Z","end":"2026-02-18T22:54:34.972705Z","steps":["trace[1447666235] 'range keys from in-memory index tree' (duration: 503.036292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:34.97274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:34.469494Z","time spent":"503.239626ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:54:34.972983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.056863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:34.97301Z","caller":"traceutil/trace.go:171","msg":"trace[1854765339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"200.120655ms","start":"2026-02-18T22:54:34.772881Z","end":"2026-02-18T22:54:34.973002Z","steps":["trace[1854765339] 'range keys from in-memory index tree' (duration: 199.997911ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:35.229631Z","caller":"traceutil/trace.go:171","msg":"trace[1117922811] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"251.210243ms","start":"2026-02-18T22:54:34.978402Z","end":"2026-02-18T22:54:35.229612Z","steps":["trace[1117922811] 'process raft request' (duration: 251.1033ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:35.258636Z","caller":"traceutil/trace.go:171","msg":"trace[442166299] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"277.828913ms","start":"2026-02-18T22:54:34.98078Z","end":"2026-02-18T22:54:35.258609Z","steps":["trace[442166299] 'process raft request' (duration: 277.787962ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:35.258812Z","caller":"traceutil/trace.go:171","msg":"trace[1660431415] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"278.11494ms","start":"2026-02-18T22:54:34.980688Z","end":"2026-02-18T22:54:35.258803Z","steps":["trace[1660431415] 'process raft request' (duration: 277.767352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:42.044146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.514682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:42.044211Z","caller":"traceutil/trace.go:171","msg":"trace[812001384] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"754.129302ms","start":"2026-02-18T22:54:41.290071Z","end":"2026-02-18T22:54:42.0442Z","steps":["trace[812001384] 'read index received' (duration: 48.506047ms)","trace[812001384] 'applied index is now lower than readState.Index' (duration: 705.622695ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:42.044312Z","caller":"traceutil/trace.go:171","msg":"trace[1604929728] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"793.908608ms","start":"2026-02-18T22:54:41.250397Z","end":"2026-02-18T22:54:42.044306Z","steps":["trace[1604929728] 'process raft request' (duration: 88.186251ms)","trace[1604929728] 'compare' (duration: 705.161374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:42.044341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:41.250387Z","time spent":"793.93896ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:42.044467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.396888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-18T22:54:42.044481Z","caller":"traceutil/trace.go:171","msg":"trace[787651421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"754.432939ms","start":"2026-02-18T22:54:41.290043Z","end":"2026-02-18T22:54:42.044476Z","steps":["trace[787651421] 'agreement among raft nodes before linearized reading' (duration: 754.375108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:42.044494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:41.290029Z","time spent":"754.4633ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T22:54:42.04457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.479171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-18T22:54:42.044581Z","caller":"traceutil/trace.go:171","msg":"trace[886222021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:957; }","duration":"754.518121ms","start":"2026-02-18T22:54:41.290059Z","end":"2026-02-18T22:54:42.044577Z","steps":["trace[886222021] 'agreement among raft nodes before linearized reading' (duration: 754.48485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:42.044592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:41.290043Z","time spent":"754.546632ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-18T22:54:42.044679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.656577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:42.04469Z","caller":"traceutil/trace.go:171","msg":"trace[1580764672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"274.697598ms","start":"2026-02-18T22:54:41.769989Z","end":"2026-02-18T22:54:42.044687Z","steps":["trace[1580764672] 'agreement among raft nodes before linearized reading' (duration: 274.677988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:42.044865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.177414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:54:42.046248Z","caller":"traceutil/trace.go:171","msg":"trace[1612699689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"436.232764ms","start":"2026-02-18T22:54:41.608646Z","end":"2026-02-18T22:54:42.044879Z","steps":["trace[1612699689] 'agreement among raft nodes before linearized reading' (duration: 436.180813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:42.046273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:41.608627Z","time spent":"437.639908ms","remote":"127.0.0.1:48220","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":"info","ts":"2026-02-18T22:54:50.438553Z","caller":"traceutil/trace.go:171","msg":"trace[701695664] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"401.217895ms","start":"2026-02-18T22:54:50.037315Z","end":"2026-02-18T22:54:50.438532Z","steps":["trace[701695664] 'process raft request' (duration: 400.97687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:50.438669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:50.0373Z","time spent":"401.308627ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:39.21536Z","caller":"traceutil/trace.go:171","msg":"trace[9637316] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"118.940545ms","start":"2026-02-18T22:55:39.096391Z","end":"2026-02-18T22:55:39.215332Z","steps":["trace[9637316] 'process raft request' (duration: 118.904524ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:39.215614Z","caller":"traceutil/trace.go:171","msg":"trace[1245391673] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"120.295637ms","start":"2026-02-18T22:55:39.09531Z","end":"2026-02-18T22:55:39.215606Z","steps":["trace[1245391673] 'process raft request' (duration: 94.971324ms)","trace[1245391673] 'compare' (duration: 24.75487ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:39.749285Z","caller":"traceutil/trace.go:171","msg":"trace[1041604839] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"193.693655ms","start":"2026-02-18T22:55:39.555566Z","end":"2026-02-18T22:55:39.74926Z","steps":["trace[1041604839] 'process raft request' (duration: 193.561102ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:58.555975Z","caller":"traceutil/trace.go:171","msg":"trace[1356208083] transaction","detail":"{read_only:false; response_revision:1805; number_of_response:1; }","duration":"145.68156ms","start":"2026-02-18T22:57:58.410271Z","end":"2026-02-18T22:57:58.555952Z","steps":["trace[1356208083] 'process raft request' (duration: 145.509426ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:01.893158Z","caller":"traceutil/trace.go:171","msg":"trace[1756679090] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"240.983368ms","start":"2026-02-18T22:58:01.652154Z","end":"2026-02-18T22:58:01.893137Z","steps":["trace[1756679090] 'read index received' (duration: 240.801524ms)","trace[1756679090] 'applied index is now lower than readState.Index' (duration: 180.974µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:01.893527Z","caller":"traceutil/trace.go:171","msg":"trace[380585223] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"356.220109ms","start":"2026-02-18T22:58:01.537294Z","end":"2026-02-18T22:58:01.893514Z","steps":["trace[380585223] 'process raft request' (duration: 355.717827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:01.893756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:01.537271Z","time spent":"356.418454ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:58:01.894056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.89705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-18T22:58:01.894208Z","caller":"traceutil/trace.go:171","msg":"trace[119839743] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1814; }","duration":"242.077184ms","start":"2026-02-18T22:58:01.652117Z","end":"2026-02-18T22:58:01.894195Z","steps":["trace[119839743] 'agreement among raft nodes before linearized reading' (duration: 241.849959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:01.894414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.531229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:58:01.894512Z","caller":"traceutil/trace.go:171","msg":"trace[1195226160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1814; }","duration":"121.667561ms","start":"2026-02-18T22:58:01.772834Z","end":"2026-02-18T22:58:01.894501Z","steps":["trace[1195226160] 'agreement among raft nodes before linearized reading' (duration: 121.553159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.094775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.205551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:58:02.094953Z","caller":"traceutil/trace.go:171","msg":"trace[1711458555] linearizableReadLoop","detail":"{readStateIndex:1898; appliedIndex:1895; }","duration":"117.643558ms","start":"2026-02-18T22:58:01.977294Z","end":"2026-02-18T22:58:02.094938Z","steps":["trace[1711458555] 'read index received' (duration: 24.669971ms)","trace[1711458555] 'applied index is now lower than readState.Index' (duration: 92.972677ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:02.09521Z","caller":"traceutil/trace.go:171","msg":"trace[1231363773] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"196.169915ms","start":"2026-02-18T22:58:01.899027Z","end":"2026-02-18T22:58:02.095196Z","steps":["trace[1231363773] 'process raft request' (duration: 77.473543ms)","trace[1231363773] 'compare' (duration: 117.935084ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:58:02.095445Z","caller":"traceutil/trace.go:171","msg":"trace[1305434768] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"194.963596ms","start":"2026-02-18T22:58:01.900471Z","end":"2026-02-18T22:58:02.095435Z","steps":["trace[1305434768] 'process raft request' (duration: 194.390203ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:02.095605Z","caller":"traceutil/trace.go:171","msg":"trace[2840542] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"194.157307ms","start":"2026-02-18T22:58:01.901439Z","end":"2026-02-18T22:58:02.095596Z","steps":["trace[2840542] 'process raft request' (duration: 193.469561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:02.095815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.522588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T22:58:02.095843Z","caller":"traceutil/trace.go:171","msg":"trace[1150491387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1817; }","duration":"118.59105ms","start":"2026-02-18T22:58:01.977243Z","end":"2026-02-18T22:58:02.095834Z","steps":["trace[1150491387] 'agreement among raft nodes before linearized reading' (duration: 118.484107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:03.164915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.239466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:58:03.166092Z","caller":"traceutil/trace.go:171","msg":"trace[343320836] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"1.055381487s","start":"2026-02-18T22:58:02.110689Z","end":"2026-02-18T22:58:03.16607Z","steps":["trace[343320836] 'process raft request' (duration: 1.054587568s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:03.166341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.11068Z","time spent":"1.055614452s","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:58:03.166695Z","caller":"traceutil/trace.go:171","msg":"trace[757657976] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"1.055123021s","start":"2026-02-18T22:58:02.111558Z","end":"2026-02-18T22:58:03.166681Z","steps":["trace[757657976] 'process raft request' (duration: 1.053831211s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:03.166928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.111547Z","time spent":"1.055230013s","remote":"127.0.0.1:48220","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-02-18T22:58:03.166095Z","caller":"traceutil/trace.go:171","msg":"trace[1661599397] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"1.058971572s","start":"2026-02-18T22:58:02.107091Z","end":"2026-02-18T22:58:03.166062Z","steps":["trace[1661599397] 'process raft request' (duration: 356.513927ms)","trace[1661599397] 'compare' (duration: 701.029241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:58:03.167457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.107074Z","time spent":"1.060339633s","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:58:03.239516Z","caller":"traceutil/trace.go:171","msg":"trace[712059691] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1898; }","duration":"466.426232ms","start":"2026-02-18T22:58:02.773065Z","end":"2026-02-18T22:58:03.239492Z","steps":["trace[712059691] 'read index received' (duration: 25.32µs)","trace[712059691] 'applied index is now lower than readState.Index' (duration: 466.399692ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:58:03.239677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.610796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-18T22:58:03.241132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.441158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:58:03.241181Z","caller":"traceutil/trace.go:171","msg":"trace[811345060] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1820; }","duration":"405.530709ms","start":"2026-02-18T22:58:02.835638Z","end":"2026-02-18T22:58:03.241169Z","steps":["trace[811345060] 'agreement among raft nodes before linearized reading' (duration: 405.439457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:03.241223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.835628Z","time spent":"405.585921ms","remote":"127.0.0.1:48186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:58:03.242268Z","caller":"traceutil/trace.go:171","msg":"trace[2015478481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1820; }","duration":"469.254689ms","start":"2026-02-18T22:58:02.773004Z","end":"2026-02-18T22:58:03.242259Z","steps":["trace[2015478481] 'agreement among raft nodes before linearized reading' (duration: 466.617707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:03.242321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:58:02.772988Z","time spent":"469.32236ms","remote":"127.0.0.1:47930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:58:03.550389Z","caller":"traceutil/trace.go:171","msg":"trace[723641078] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"161.861218ms","start":"2026-02-18T22:58:03.388512Z","end":"2026-02-18T22:58:03.550373Z","steps":["trace[723641078] 'process raft request' (duration: 161.744615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:58:03.701318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.56747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-18T22:58:03.701402Z","caller":"traceutil/trace.go:171","msg":"trace[1981723678] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1823; }","duration":"138.688182ms","start":"2026-02-18T22:58:03.562694Z","end":"2026-02-18T22:58:03.701382Z","steps":["trace[1981723678] 'range keys from in-memory index tree' (duration: 138.399166ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:58:03.702059Z","caller":"traceutil/trace.go:171","msg":"trace[342312152] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"135.302663ms","start":"2026-02-18T22:58:03.566739Z","end":"2026-02-18T22:58:03.702042Z","steps":["trace[342312152] 'process raft request' (duration: 115.440265ms)","trace[342312152] 'compare' (duration: 19.774456ms)"],"step_count":2}