{"level":"warn","ts":"2026-01-30T23:23:17.043475Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T23:23:17.043541Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-30T23:23:17.043594Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T23:23:17.043602Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-01-30T23:23:17.043618Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-30T23:23:17.044172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-01-30T23:23:17.044235Z","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.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-30T23:23:17.048429Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.017609ms"} {"level":"info","ts":"2026-01-30T23:23:17.064873Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-01-30T23:23:17.064931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T23:23:17.064947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-01-30T23:23:17.064956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T23:23:17.064962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-01-30T23:23:17.064989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-01-30T23:23:17.071625Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T23:23:17.073181Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T23:23:17.074405Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-30T23:23:17.077484Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T23:23:17.079363Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T23:23:17.079451Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-30T23:23:17.079474Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-30T23:23:17.07948Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-30T23:23:17.079626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-01-30T23:23:17.079676Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-01-30T23:23:17.084178Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-30T23:23:17.084309Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T23:23:17.084324Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T23:23:17.084385Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-01-30T23:23:17.084393Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-01-30T23:23:18.06643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T23:23:18.066488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T23:23:18.066505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-01-30T23:23:18.066516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-01-30T23:23:18.066521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-01-30T23:23:18.066539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-01-30T23:23:18.066546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-01-30T23:23:18.073804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T23:23:18.079495Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T23:23:18.079608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T23:23:18.079926Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T23:23:18.080062Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T23:23:18.080071Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T23:23:18.084018Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T23:23:18.084691Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T23:23:18.085396Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T23:23:18.085426Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T23:23:18.085691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-01-30T23:23:37.988568Z","caller":"traceutil/trace.go:171","msg":"trace[426069750] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"575.605995ms","start":"2026-01-30T23:23:37.412939Z","end":"2026-01-30T23:23:37.988545Z","steps":["trace[426069750] 'process raft request' (duration: 575.465872ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:37.989097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:37.412928Z","time spent":"575.722948ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:23:37.989582Z","caller":"traceutil/trace.go:171","msg":"trace[1193829952] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:429; }","duration":"448.530356ms","start":"2026-01-30T23:23:37.541039Z","end":"2026-01-30T23:23:37.989569Z","steps":["trace[1193829952] 'read index received' (duration: 448.521036ms)","trace[1193829952] 'applied index is now lower than readState.Index' (duration: 8.18µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:23:37.989676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.635958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-30T23:23:37.991027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.633736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-30T23:23:37.991094Z","caller":"traceutil/trace.go:171","msg":"trace[2092787203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"369.709218ms","start":"2026-01-30T23:23:37.621346Z","end":"2026-01-30T23:23:37.991055Z","steps":["trace[2092787203] 'agreement among raft nodes before linearized reading' (duration: 369.639946ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:37.991127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:37.621326Z","time spent":"369.793739ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T23:23:37.991317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.280312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-30T23:23:37.991348Z","caller":"traceutil/trace.go:171","msg":"trace[1485185461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"254.373525ms","start":"2026-01-30T23:23:37.736966Z","end":"2026-01-30T23:23:37.991339Z","steps":["trace[1485185461] 'agreement among raft nodes before linearized reading' (duration: 254.280833ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:37.991534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.545294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T23:23:37.991565Z","caller":"traceutil/trace.go:171","msg":"trace[2066911615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"263.644345ms","start":"2026-01-30T23:23:37.727912Z","end":"2026-01-30T23:23:37.991557Z","steps":["trace[2066911615] 'agreement among raft nodes before linearized reading' (duration: 263.554524ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:37.993456Z","caller":"traceutil/trace.go:171","msg":"trace[2070394309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"452.410851ms","start":"2026-01-30T23:23:37.541028Z","end":"2026-01-30T23:23:37.993439Z","steps":["trace[2070394309] 'agreement among raft nodes before linearized reading' (duration: 448.618527ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:37.993506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:37.540986Z","time spent":"452.507053ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T23:23:38.456959Z","caller":"traceutil/trace.go:171","msg":"trace[1843143281] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"462.465679ms","start":"2026-01-30T23:23:37.994461Z","end":"2026-01-30T23:23:38.456926Z","steps":["trace[1843143281] 'process raft request' (duration: 428.338876ms)","trace[1843143281] 'compare' (duration: 32.869496ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:23:38.457074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:37.994449Z","time spent":"462.565451ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:23:38.45939Z","caller":"traceutil/trace.go:171","msg":"trace[1239019075] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"461.639261ms","start":"2026-01-30T23:23:37.997738Z","end":"2026-01-30T23:23:38.459377Z","steps":["trace[1239019075] 'process raft request' (duration: 458.064424ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:38.459708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:37.997732Z","time spent":"461.933758ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:23:38.460733Z","caller":"traceutil/trace.go:171","msg":"trace[1607953730] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"462.95558ms","start":"2026-01-30T23:23:37.997768Z","end":"2026-01-30T23:23:38.460724Z","steps":["trace[1607953730] 'process raft request' (duration: 461.550559ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:38.462611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:37.997765Z","time spent":"463.128364ms","remote":"127.0.0.1:48506","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-01-30T23:23:44.392087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.383764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-01-30T23:23:44.392165Z","caller":"traceutil/trace.go:171","msg":"trace[2060029533] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:431; }","duration":"187.491987ms","start":"2026-01-30T23:23:44.204654Z","end":"2026-01-30T23:23:44.392146Z","steps":["trace[2060029533] 'range keys from in-memory index tree' (duration: 187.304083ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:48.164576Z","caller":"traceutil/trace.go:171","msg":"trace[720853172] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"120.859074ms","start":"2026-01-30T23:23:48.043699Z","end":"2026-01-30T23:23:48.164558Z","steps":["trace[720853172] 'process raft request' (duration: 120.748082ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:50.311722Z","caller":"traceutil/trace.go:171","msg":"trace[229474140] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"137.530069ms","start":"2026-01-30T23:23:50.17416Z","end":"2026-01-30T23:23:50.31169Z","steps":["trace[229474140] 'process raft request' (duration: 137.435667ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:55.030742Z","caller":"traceutil/trace.go:171","msg":"trace[604536935] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"339.873977ms","start":"2026-01-30T23:23:54.690844Z","end":"2026-01-30T23:23:55.030718Z","steps":["trace[604536935] 'read index received' (duration: 339.714964ms)","trace[604536935] 'applied index is now lower than readState.Index' (duration: 157.973µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T23:23:55.031022Z","caller":"traceutil/trace.go:171","msg":"trace[644583005] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"398.962266ms","start":"2026-01-30T23:23:54.632049Z","end":"2026-01-30T23:23:55.031011Z","steps":["trace[644583005] 'process raft request' (duration: 398.565317ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:55.03112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:54.632034Z","time spent":"399.026268ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T23:23:55.031297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.45058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-30T23:23:55.031335Z","caller":"traceutil/trace.go:171","msg":"trace[443307134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"340.486981ms","start":"2026-01-30T23:23:54.690839Z","end":"2026-01-30T23:23:55.031326Z","steps":["trace[443307134] 'agreement among raft nodes before linearized reading' (duration: 340.42391ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:55.031364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:54.690791Z","time spent":"340.566633ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T23:23:55.031485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.262976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T23:23:55.03151Z","caller":"traceutil/trace.go:171","msg":"trace[351795886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"340.297157ms","start":"2026-01-30T23:23:54.691206Z","end":"2026-01-30T23:23:55.031503Z","steps":["trace[351795886] 'agreement among raft nodes before linearized reading' (duration: 340.255566ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:55.031536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:54.691201Z","time spent":"340.327549ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T23:23:55.031701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.516781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l4lnn\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-30T23:23:55.031775Z","caller":"traceutil/trace.go:171","msg":"trace[1895482153] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l4lnn; range_end:; response_count:1; response_revision:495; }","duration":"111.617193ms","start":"2026-01-30T23:23:54.92015Z","end":"2026-01-30T23:23:55.031767Z","steps":["trace[1895482153] 'agreement among raft nodes before linearized reading' (duration: 111.522621ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:55.391306Z","caller":"traceutil/trace.go:171","msg":"trace[1228945131] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"340.133083ms","start":"2026-01-30T23:23:55.051151Z","end":"2026-01-30T23:23:55.391284Z","steps":["trace[1228945131] 'process raft request' (duration: 340.01013ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:55.391452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:55.051135Z","time spent":"340.254165ms","remote":"127.0.0.1:48426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:23:55.872179Z","caller":"traceutil/trace.go:171","msg":"trace[589494005] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:512; }","duration":"316.793994ms","start":"2026-01-30T23:23:55.555368Z","end":"2026-01-30T23:23:55.872162Z","steps":["trace[589494005] 'read index received' (duration: 316.63971ms)","trace[589494005] 'applied index is now lower than readState.Index' (duration: 153.684µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:23:55.872299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.917697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:23:55.872322Z","caller":"traceutil/trace.go:171","msg":"trace[525030790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"316.982018ms","start":"2026-01-30T23:23:55.555333Z","end":"2026-01-30T23:23:55.872316Z","steps":["trace[525030790] 'agreement among raft nodes before linearized reading' (duration: 316.891296ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:55.872344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:55.555318Z","time spent":"317.021849ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T23:23:55.872558Z","caller":"traceutil/trace.go:171","msg":"trace[1255881041] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"815.658867ms","start":"2026-01-30T23:23:55.056891Z","end":"2026-01-30T23:23:55.87255Z","steps":["trace[1255881041] 'process raft request' (duration: 815.161636ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:55.872613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:55.05688Z","time spent":"815.694987ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:23:55.872761Z","caller":"traceutil/trace.go:171","msg":"trace[1137002694] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"815.787779ms","start":"2026-01-30T23:23:55.056964Z","end":"2026-01-30T23:23:55.872752Z","steps":["trace[1137002694] 'process raft request' (duration: 815.175386ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:55.872798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:23:55.056958Z","time spent":"815.81916ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:23:58.450835Z","caller":"traceutil/trace.go:171","msg":"trace[244178461] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"236.419802ms","start":"2026-01-30T23:23:58.214392Z","end":"2026-01-30T23:23:58.450812Z","steps":["trace[244178461] 'process raft request' (duration: 236.284049ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:58.456302Z","caller":"traceutil/trace.go:171","msg":"trace[1492956509] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"208.815371ms","start":"2026-01-30T23:23:58.247475Z","end":"2026-01-30T23:23:58.456291Z","steps":["trace[1492956509] 'process raft request' (duration: 208.264899ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:58.457277Z","caller":"traceutil/trace.go:171","msg":"trace[2016946731] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"154.256992ms","start":"2026-01-30T23:23:58.30301Z","end":"2026-01-30T23:23:58.457267Z","steps":["trace[2016946731] 'process raft request' (duration: 152.926252ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:23:58.458641Z","caller":"traceutil/trace.go:171","msg":"trace[570984394] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"190.900421ms","start":"2026-01-30T23:23:58.267729Z","end":"2026-01-30T23:23:58.45863Z","steps":["trace[570984394] 'read index received' (duration: 183.599512ms)","trace[570984394] 'applied index is now lower than readState.Index' (duration: 7.300389ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:23:58.458839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.093385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:23:58.458867Z","caller":"traceutil/trace.go:171","msg":"trace[1812931407] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:537; }","duration":"191.172017ms","start":"2026-01-30T23:23:58.267688Z","end":"2026-01-30T23:23:58.45886Z","steps":["trace[1812931407] 'agreement among raft nodes before linearized reading' (duration: 191.089055ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:58.460332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.559547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:23:58.460363Z","caller":"traceutil/trace.go:171","msg":"trace[1994378014] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:537; }","duration":"192.601538ms","start":"2026-01-30T23:23:58.267755Z","end":"2026-01-30T23:23:58.460356Z","steps":["trace[1994378014] 'agreement among raft nodes before linearized reading' (duration: 192.552507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:58.460573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.613137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-30T23:23:58.4606Z","caller":"traceutil/trace.go:171","msg":"trace[427944787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"107.659167ms","start":"2026-01-30T23:23:58.352934Z","end":"2026-01-30T23:23:58.460594Z","steps":["trace[427944787] 'agreement among raft nodes before linearized reading' (duration: 107.603136ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:58.4607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.190858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:23:58.460723Z","caller":"traceutil/trace.go:171","msg":"trace[654289225] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:537; }","duration":"158.226879ms","start":"2026-01-30T23:23:58.30249Z","end":"2026-01-30T23:23:58.460717Z","steps":["trace[654289225] 'agreement among raft nodes before linearized reading' (duration: 158.192148ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:58.460809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.323312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:23:58.46083Z","caller":"traceutil/trace.go:171","msg":"trace[362274297] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:537; }","duration":"158.359322ms","start":"2026-01-30T23:23:58.302466Z","end":"2026-01-30T23:23:58.460825Z","steps":["trace[362274297] 'agreement among raft nodes before linearized reading' (duration: 158.328831ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:58.460904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.455324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:23:58.460926Z","caller":"traceutil/trace.go:171","msg":"trace[1754892868] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:537; }","duration":"158.508055ms","start":"2026-01-30T23:23:58.302413Z","end":"2026-01-30T23:23:58.460921Z","steps":["trace[1754892868] 'agreement among raft nodes before linearized reading' (duration: 158.476524ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:23:58.460996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.205222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:23:58.461019Z","caller":"traceutil/trace.go:171","msg":"trace[2038961616] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:537; }","duration":"193.245622ms","start":"2026-01-30T23:23:58.267767Z","end":"2026-01-30T23:23:58.461012Z","steps":["trace[2038961616] 'agreement among raft nodes before linearized reading' (duration: 193.215941ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:08.661316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.678291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T23:24:08.661579Z","caller":"traceutil/trace.go:171","msg":"trace[659339176] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"604.440233ms","start":"2026-01-30T23:24:08.05712Z","end":"2026-01-30T23:24:08.66156Z","steps":["trace[659339176] 'process raft request' (duration: 31.263361ms)","trace[659339176] 'compare' (duration: 572.245032ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:08.661709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:08.057111Z","time spent":"604.535176ms","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T23:24:09.472283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.228005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T23:24:09.472513Z","caller":"traceutil/trace.go:171","msg":"trace[1539468188] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:655; }","duration":"928.252292ms","start":"2026-01-30T23:24:08.544246Z","end":"2026-01-30T23:24:09.472498Z","steps":["trace[1539468188] 'read index received' (duration: 348.68176ms)","trace[1539468188] 'applied index is now lower than readState.Index' (duration: 579.569332ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T23:24:09.472699Z","caller":"traceutil/trace.go:171","msg":"trace[560633860] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.415516501s","start":"2026-01-30T23:24:08.057169Z","end":"2026-01-30T23:24:09.472686Z","steps":["trace[560633860] 'process raft request' (duration: 835.814986ms)","trace[560633860] 'compare' (duration: 579.00872ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:09.472755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:08.057152Z","time spent":"1.415573542s","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:09.47342Z","caller":"traceutil/trace.go:171","msg":"trace[1391005213] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.415287236s","start":"2026-01-30T23:24:08.058116Z","end":"2026-01-30T23:24:09.473404Z","steps":["trace[1391005213] 'process raft request' (duration: 1.414263344s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:09.473513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:08.058111Z","time spent":"1.415367408s","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T23:24:09.473729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.469046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:24:09.473804Z","caller":"traceutil/trace.go:171","msg":"trace[957655027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"928.603949ms","start":"2026-01-30T23:24:08.545182Z","end":"2026-01-30T23:24:09.473786Z","steps":["trace[957655027] 'agreement among raft nodes before linearized reading' (duration: 928.487196ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:09.473843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:08.545178Z","time spent":"928.65825ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T23:24:09.474099Z","caller":"traceutil/trace.go:171","msg":"trace[1023631567] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.399066322s","start":"2026-01-30T23:24:08.07502Z","end":"2026-01-30T23:24:09.474086Z","steps":["trace[1023631567] 'process raft request' (duration: 1.397439607s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:09.474182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:08.074971Z","time spent":"1.399155534s","remote":"127.0.0.1:48426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4796,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T23:24:09.474418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.178813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-30T23:24:09.474447Z","caller":"traceutil/trace.go:171","msg":"trace[1111023048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"930.245415ms","start":"2026-01-30T23:24:08.544193Z","end":"2026-01-30T23:24:09.474438Z","steps":["trace[1111023048] 'agreement among raft nodes before linearized reading' (duration: 930.072681ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:09.474473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:08.544173Z","time spent":"930.294425ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T23:24:09.501655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.001654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-30T23:24:09.501719Z","caller":"traceutil/trace.go:171","msg":"trace[317220165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"414.091905ms","start":"2026-01-30T23:24:09.087616Z","end":"2026-01-30T23:24:09.501708Z","steps":["trace[317220165] 'agreement among raft nodes before linearized reading' (duration: 387.039276ms)","trace[317220165] 'range keys from bolt db' (duration: 26.967727ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:09.501742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.087601Z","time spent":"414.132767ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-30T23:24:09.911355Z","caller":"traceutil/trace.go:171","msg":"trace[1075290467] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"409.828392ms","start":"2026-01-30T23:24:09.50151Z","end":"2026-01-30T23:24:09.911338Z","steps":["trace[1075290467] 'process raft request' (duration: 332.816804ms)","trace[1075290467] 'compare' (duration: 76.65528ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:09.911436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.501502Z","time spent":"409.891483ms","remote":"127.0.0.1:48420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1018,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:10.068727Z","caller":"traceutil/trace.go:171","msg":"trace[98925222] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"566.663381ms","start":"2026-01-30T23:24:09.50205Z","end":"2026-01-30T23:24:10.068713Z","steps":["trace[98925222] 'process raft request' (duration: 564.453602ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:10.06882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.502043Z","time spent":"566.724283ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:10.06961Z","caller":"traceutil/trace.go:171","msg":"trace[1505752793] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"562.694934ms","start":"2026-01-30T23:24:09.50691Z","end":"2026-01-30T23:24:10.069604Z","steps":["trace[1505752793] 'process raft request' (duration: 561.061178ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:10.069671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.506903Z","time spent":"562.726674ms","remote":"127.0.0.1:48512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:10.072952Z","caller":"traceutil/trace.go:171","msg":"trace[1442190106] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"565.928134ms","start":"2026-01-30T23:24:09.507017Z","end":"2026-01-30T23:24:10.072945Z","steps":["trace[1442190106] 'process raft request' (duration: 561.676702ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:10.073002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.507002Z","time spent":"565.980915ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:10.073267Z","caller":"traceutil/trace.go:171","msg":"trace[540592131] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:659; }","duration":"133.264124ms","start":"2026-01-30T23:24:09.939996Z","end":"2026-01-30T23:24:10.07326Z","steps":["trace[540592131] 'read index received' (duration: 126.461806ms)","trace[540592131] 'applied index is now lower than readState.Index' (duration: 6.801798ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T23:24:10.073385Z","caller":"traceutil/trace.go:171","msg":"trace[636906514] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"562.520281ms","start":"2026-01-30T23:24:09.510859Z","end":"2026-01-30T23:24:10.073379Z","steps":["trace[636906514] 'process raft request' (duration: 558.726528ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:10.073413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.510852Z","time spent":"562.544371ms","remote":"127.0.0.1:48426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4832,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:10.073475Z","caller":"traceutil/trace.go:171","msg":"trace[1721945368] transaction","detail":"{read_only:false; number_of_response:1; response_revision:643; }","duration":"561.219392ms","start":"2026-01-30T23:24:09.512251Z","end":"2026-01-30T23:24:10.073471Z","steps":["trace[1721945368] 'process raft request' (duration: 560.958506ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:10.073497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.512246Z","time spent":"561.238762ms","remote":"127.0.0.1:48740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3879,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:10.073583Z","caller":"traceutil/trace.go:171","msg":"trace[1919178601] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"561.228362ms","start":"2026-01-30T23:24:09.51235Z","end":"2026-01-30T23:24:10.073579Z","steps":["trace[1919178601] 'process raft request' (duration: 560.876235ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:10.073615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:09.512345Z","time spent":"561.247372ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T23:24:10.073715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.718604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T23:24:10.073739Z","caller":"traceutil/trace.go:171","msg":"trace[1966383605] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:644; }","duration":"133.785485ms","start":"2026-01-30T23:24:09.939949Z","end":"2026-01-30T23:24:10.073735Z","steps":["trace[1966383605] 'agreement among raft nodes before linearized reading' (duration: 133.717234ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:13.033444Z","caller":"traceutil/trace.go:171","msg":"trace[2088237151] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"230.200197ms","start":"2026-01-30T23:24:12.803226Z","end":"2026-01-30T23:24:13.033426Z","steps":["trace[2088237151] 'process raft request' (duration: 230.098925ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:13.268676Z","caller":"traceutil/trace.go:171","msg":"trace[1273791149] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"221.463377ms","start":"2026-01-30T23:24:13.047195Z","end":"2026-01-30T23:24:13.268658Z","steps":["trace[1273791149] 'process raft request' (duration: 221.354294ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:13.361403Z","caller":"traceutil/trace.go:171","msg":"trace[2104839013] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"250.753126ms","start":"2026-01-30T23:24:13.110632Z","end":"2026-01-30T23:24:13.361385Z","steps":["trace[2104839013] 'process raft request' (duration: 250.634943ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:21.778964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.208778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T23:24:21.779033Z","caller":"traceutil/trace.go:171","msg":"trace[961746986] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"238.668562ms","start":"2026-01-30T23:24:21.540352Z","end":"2026-01-30T23:24:21.77902Z","steps":["trace[961746986] 'read index received' (duration: 2.32022ms)","trace[961746986] 'applied index is now lower than readState.Index' (duration: 236.347872ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:21.779109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.756544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:24:21.779122Z","caller":"traceutil/trace.go:171","msg":"trace[904536240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"238.787954ms","start":"2026-01-30T23:24:21.540329Z","end":"2026-01-30T23:24:21.779117Z","steps":["trace[904536240] 'agreement among raft nodes before linearized reading' (duration: 238.718942ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:21.77922Z","caller":"traceutil/trace.go:171","msg":"trace[1534928593] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"303.782911ms","start":"2026-01-30T23:24:21.475431Z","end":"2026-01-30T23:24:21.779214Z","steps":["trace[1534928593] 'process raft request' (duration: 67.270496ms)","trace[1534928593] 'compare' (duration: 236.095636ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:21.779247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:21.47542Z","time spent":"303.811382ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T23:24:34.607075Z","caller":"traceutil/trace.go:171","msg":"trace[365933366] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"439.011958ms","start":"2026-01-30T23:24:34.168051Z","end":"2026-01-30T23:24:34.607062Z","steps":["trace[365933366] 'read index received' (duration: 438.937587ms)","trace[365933366] 'applied index is now lower than readState.Index' (duration: 73.691µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T23:24:34.607148Z","caller":"traceutil/trace.go:171","msg":"trace[125212186] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"543.422926ms","start":"2026-01-30T23:24:34.06372Z","end":"2026-01-30T23:24:34.607143Z","steps":["trace[125212186] 'process raft request' (duration: 543.259832ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:34.607206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:34.063705Z","time spent":"543.455876ms","remote":"127.0.0.1:48506","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-01-30T23:24:34.607323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.272834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-30T23:24:34.607339Z","caller":"traceutil/trace.go:171","msg":"trace[329861074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:736; }","duration":"439.304354ms","start":"2026-01-30T23:24:34.16803Z","end":"2026-01-30T23:24:34.607334Z","steps":["trace[329861074] 'agreement among raft nodes before linearized reading' (duration: 439.243603ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:34.607354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:34.168017Z","time spent":"439.332114ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T23:24:34.607544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.774161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-30T23:24:34.607558Z","caller":"traceutil/trace.go:171","msg":"trace[1682490448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"349.800252ms","start":"2026-01-30T23:24:34.257754Z","end":"2026-01-30T23:24:34.607554Z","steps":["trace[1682490448] 'agreement among raft nodes before linearized reading' (duration: 349.758691ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:34.607548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.639877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-30T23:24:34.607602Z","caller":"traceutil/trace.go:171","msg":"trace[719943899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"426.71439ms","start":"2026-01-30T23:24:34.180874Z","end":"2026-01-30T23:24:34.607589Z","steps":["trace[719943899] 'agreement among raft nodes before linearized reading' (duration: 426.549406ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:34.607639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:34.180868Z","time spent":"426.76313ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-30T23:24:34.607723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.920809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-01-30T23:24:34.60757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:34.257748Z","time spent":"349.819982ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-30T23:24:34.607746Z","caller":"traceutil/trace.go:171","msg":"trace[465275761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"357.96119ms","start":"2026-01-30T23:24:34.24978Z","end":"2026-01-30T23:24:34.607741Z","steps":["trace[465275761] 'agreement among raft nodes before linearized reading' (duration: 357.915499ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:34.60776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:34.249771Z","time spent":"357.98624ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T23:24:34.607813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.583345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-30T23:24:34.607823Z","caller":"traceutil/trace.go:171","msg":"trace[2121567128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:736; }","duration":"354.619316ms","start":"2026-01-30T23:24:34.253201Z","end":"2026-01-30T23:24:34.60782Z","steps":["trace[2121567128] 'agreement among raft nodes before linearized reading' (duration: 354.588996ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:34.607835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:34.253195Z","time spent":"354.636687ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-30T23:24:36.759455Z","caller":"traceutil/trace.go:171","msg":"trace[346859007] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"125.12921ms","start":"2026-01-30T23:24:36.634301Z","end":"2026-01-30T23:24:36.759431Z","steps":["trace[346859007] 'process raft request' (duration: 124.985717ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:36.760451Z","caller":"traceutil/trace.go:171","msg":"trace[1076584634] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:769; }","duration":"124.301442ms","start":"2026-01-30T23:24:36.636132Z","end":"2026-01-30T23:24:36.760433Z","steps":["trace[1076584634] 'read index received' (duration: 124.295922ms)","trace[1076584634] 'applied index is now lower than readState.Index' (duration: 4.21µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:36.762599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.446918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-30T23:24:36.762643Z","caller":"traceutil/trace.go:171","msg":"trace[1174825415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"126.51399ms","start":"2026-01-30T23:24:36.636118Z","end":"2026-01-30T23:24:36.762632Z","steps":["trace[1174825415] 'agreement among raft nodes before linearized reading' (duration: 124.398285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:36.823721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.259796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-30T23:24:36.823787Z","caller":"traceutil/trace.go:171","msg":"trace[635364451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"187.328117ms","start":"2026-01-30T23:24:36.636437Z","end":"2026-01-30T23:24:36.823765Z","steps":["trace[635364451] 'agreement among raft nodes before linearized reading' (duration: 187.214725ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:36.823914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.697971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-30T23:24:36.823927Z","caller":"traceutil/trace.go:171","msg":"trace[1151002282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"185.720622ms","start":"2026-01-30T23:24:36.638203Z","end":"2026-01-30T23:24:36.823923Z","steps":["trace[1151002282] 'agreement among raft nodes before linearized reading' (duration: 185.682751ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:36.824017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.61776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-30T23:24:36.824054Z","caller":"traceutil/trace.go:171","msg":"trace[1059668713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"186.663842ms","start":"2026-01-30T23:24:36.637379Z","end":"2026-01-30T23:24:36.824043Z","steps":["trace[1059668713] 'agreement among raft nodes before linearized reading' (duration: 186.57443ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:38.900402Z","caller":"traceutil/trace.go:171","msg":"trace[1978251645] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"124.874995ms","start":"2026-01-30T23:24:38.775509Z","end":"2026-01-30T23:24:38.900384Z","steps":["trace[1978251645] 'process raft request' (duration: 124.741282ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:45.065098Z","caller":"traceutil/trace.go:171","msg":"trace[1451942010] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"138.834359ms","start":"2026-01-30T23:24:44.926247Z","end":"2026-01-30T23:24:45.065081Z","steps":["trace[1451942010] 'process raft request' (duration: 138.582193ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:49.254119Z","caller":"traceutil/trace.go:171","msg":"trace[779772462] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:857; }","duration":"129.598969ms","start":"2026-01-30T23:24:49.124504Z","end":"2026-01-30T23:24:49.254103Z","steps":["trace[779772462] 'read index received' (duration: 129.401774ms)","trace[779772462] 'applied index is now lower than readState.Index' (duration: 196.625µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:49.254253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.730672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-30T23:24:49.254269Z","caller":"traceutil/trace.go:171","msg":"trace[1599171678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:826; }","duration":"129.786702ms","start":"2026-01-30T23:24:49.124477Z","end":"2026-01-30T23:24:49.254264Z","steps":["trace[1599171678] 'agreement among raft nodes before linearized reading' (duration: 129.68979ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:24:49.254497Z","caller":"traceutil/trace.go:171","msg":"trace[272305909] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"130.296353ms","start":"2026-01-30T23:24:49.124195Z","end":"2026-01-30T23:24:49.254491Z","steps":["trace[272305909] 'process raft request' (duration: 129.780151ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:49.254773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.368842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-30T23:24:49.254803Z","caller":"traceutil/trace.go:171","msg":"trace[1317943000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:826; }","duration":"129.410473ms","start":"2026-01-30T23:24:49.125373Z","end":"2026-01-30T23:24:49.254784Z","steps":["trace[1317943000] 'agreement among raft nodes before linearized reading' (duration: 129.376893ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:49.256029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.470608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-30T23:24:49.256058Z","caller":"traceutil/trace.go:171","msg":"trace[927737578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:826; }","duration":"131.494319ms","start":"2026-01-30T23:24:49.124547Z","end":"2026-01-30T23:24:49.256041Z","steps":["trace[927737578] 'agreement among raft nodes before linearized reading' (duration: 131.438198ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:49.256553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.195271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-01-30T23:24:49.256575Z","caller":"traceutil/trace.go:171","msg":"trace[677814899] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:826; }","duration":"132.041721ms","start":"2026-01-30T23:24:49.124527Z","end":"2026-01-30T23:24:49.256569Z","steps":["trace[677814899] 'agreement among raft nodes before linearized reading' (duration: 130.172601ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:57.138758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.014685ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-30T23:24:57.138838Z","caller":"traceutil/trace.go:171","msg":"trace[1840522364] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"595.662756ms","start":"2026-01-30T23:24:56.543162Z","end":"2026-01-30T23:24:57.138825Z","steps":["trace[1840522364] 'read index received' (duration: 179.535108ms)","trace[1840522364] 'applied index is now lower than readState.Index' (duration: 416.126608ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:24:57.138891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.720867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:24:57.138908Z","caller":"traceutil/trace.go:171","msg":"trace[763207230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:848; }","duration":"595.767899ms","start":"2026-01-30T23:24:56.543133Z","end":"2026-01-30T23:24:57.138901Z","steps":["trace[763207230] 'agreement among raft nodes before linearized reading' (duration: 595.724418ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:24:57.138931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:24:56.543119Z","time spent":"595.807889ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T23:25:11.488071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:25:11.166638Z","time spent":"321.430573ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-30T23:25:11.859018Z","caller":"traceutil/trace.go:171","msg":"trace[520969498] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"317.18189ms","start":"2026-01-30T23:25:11.541821Z","end":"2026-01-30T23:25:11.859003Z","steps":["trace[520969498] 'read index received' (duration: 307.13096ms)","trace[520969498] 'applied index is now lower than readState.Index' (duration: 10.0502ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T23:25:11.859109Z","caller":"traceutil/trace.go:171","msg":"trace[14544298] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"370.156736ms","start":"2026-01-30T23:25:11.488944Z","end":"2026-01-30T23:25:11.859101Z","steps":["trace[14544298] 'process raft request' (duration: 360.051425ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:11.859175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:25:11.488931Z","time spent":"370.194477ms","remote":"127.0.0.1:48276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T23:25:11.859437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.190091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-01-30T23:25:11.859463Z","caller":"traceutil/trace.go:171","msg":"trace[1291909088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:907; }","duration":"301.236573ms","start":"2026-01-30T23:25:11.558218Z","end":"2026-01-30T23:25:11.859455Z","steps":["trace[1291909088] 'agreement among raft nodes before linearized reading' (duration: 301.12828ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:11.859481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:25:11.558208Z","time spent":"301.269293ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-30T23:25:11.859599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.417115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-30T23:25:11.859617Z","caller":"traceutil/trace.go:171","msg":"trace[1940798132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"212.445115ms","start":"2026-01-30T23:25:11.647166Z","end":"2026-01-30T23:25:11.859611Z","steps":["trace[1940798132] 'agreement among raft nodes before linearized reading' (duration: 212.395554ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:11.85972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.754782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-30T23:25:11.859736Z","caller":"traceutil/trace.go:171","msg":"trace[1316770917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"229.782694ms","start":"2026-01-30T23:25:11.629949Z","end":"2026-01-30T23:25:11.859731Z","steps":["trace[1316770917] 'agreement among raft nodes before linearized reading' (duration: 229.738393ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:11.859869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.080292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T23:25:11.859886Z","caller":"traceutil/trace.go:171","msg":"trace[641516366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"231.109142ms","start":"2026-01-30T23:25:11.628771Z","end":"2026-01-30T23:25:11.859881Z","steps":["trace[641516366] 'agreement among raft nodes before linearized reading' (duration: 231.062681ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:11.859934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.242406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-30T23:25:11.859947Z","caller":"traceutil/trace.go:171","msg":"trace[2101837024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"270.288216ms","start":"2026-01-30T23:25:11.589654Z","end":"2026-01-30T23:25:11.859942Z","steps":["trace[2101837024] 'agreement among raft nodes before linearized reading' (duration: 270.251845ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:11.869154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.31634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:25:11.869189Z","caller":"traceutil/trace.go:171","msg":"trace[1067765269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"327.388491ms","start":"2026-01-30T23:25:11.541793Z","end":"2026-01-30T23:25:11.869181Z","steps":["trace[1067765269] 'agreement among raft nodes before linearized reading' (duration: 317.514087ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:11.869205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T23:25:11.541762Z","time spent":"327.439014ms","remote":"127.0.0.1:48256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T23:25:12.05286Z","caller":"traceutil/trace.go:171","msg":"trace[1162674045] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"181.885739ms","start":"2026-01-30T23:25:11.870949Z","end":"2026-01-30T23:25:12.052834Z","steps":["trace[1162674045] 'read index received' (duration: 144.462913ms)","trace[1162674045] 'applied index is now lower than readState.Index' (duration: 37.421766ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T23:25:12.053058Z","caller":"traceutil/trace.go:171","msg":"trace[2065066060] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"185.414875ms","start":"2026-01-30T23:25:11.867572Z","end":"2026-01-30T23:25:12.052987Z","steps":["trace[2065066060] 'process raft request' (duration: 147.343164ms)","trace[2065066060] 'compare' (duration: 37.701423ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:25:12.053179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.213146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-30T23:25:12.053237Z","caller":"traceutil/trace.go:171","msg":"trace[205732848] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:908; }","duration":"182.287817ms","start":"2026-01-30T23:25:11.870938Z","end":"2026-01-30T23:25:12.053226Z","steps":["trace[205732848] 'agreement among raft nodes before linearized reading' (duration: 182.138394ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:25:12.095627Z","caller":"traceutil/trace.go:171","msg":"trace[46619600] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"224.58431ms","start":"2026-01-30T23:25:11.871031Z","end":"2026-01-30T23:25:12.095616Z","steps":["trace[46619600] 'process raft request' (duration: 224.314784ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:25:12.095746Z","caller":"traceutil/trace.go:171","msg":"trace[1532845319] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"224.677561ms","start":"2026-01-30T23:25:11.871062Z","end":"2026-01-30T23:25:12.095739Z","steps":["trace[1532845319] 'process raft request' (duration: 224.350344ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:25:12.095811Z","caller":"traceutil/trace.go:171","msg":"trace[215398848] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"223.069746ms","start":"2026-01-30T23:25:11.872737Z","end":"2026-01-30T23:25:12.095807Z","steps":["trace[215398848] 'process raft request' (duration: 222.697828ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:25:12.095884Z","caller":"traceutil/trace.go:171","msg":"trace[993758721] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"221.214776ms","start":"2026-01-30T23:25:11.874665Z","end":"2026-01-30T23:25:12.09588Z","steps":["trace[993758721] 'process raft request' (duration: 220.793497ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:12.095974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.1326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:25:12.095995Z","caller":"traceutil/trace.go:171","msg":"trace[86358360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"224.162221ms","start":"2026-01-30T23:25:11.871825Z","end":"2026-01-30T23:25:12.095987Z","steps":["trace[86358360] 'agreement among raft nodes before linearized reading' (duration: 224.11986ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:25:23.946239Z","caller":"traceutil/trace.go:171","msg":"trace[1316199332] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"106.045682ms","start":"2026-01-30T23:25:23.840171Z","end":"2026-01-30T23:25:23.946217Z","steps":["trace[1316199332] 'read index received' (duration: 105.877978ms)","trace[1316199332] 'applied index is now lower than readState.Index' (duration: 166.734µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T23:25:23.946366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.171397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T23:25:23.946397Z","caller":"traceutil/trace.go:171","msg":"trace[410913799] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:957; }","duration":"106.253128ms","start":"2026-01-30T23:25:23.840135Z","end":"2026-01-30T23:25:23.946388Z","steps":["trace[410913799] 'agreement among raft nodes before linearized reading' (duration: 106.164366ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:25:23.946606Z","caller":"traceutil/trace.go:171","msg":"trace[1483108019] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"281.425811ms","start":"2026-01-30T23:25:23.665156Z","end":"2026-01-30T23:25:23.946582Z","steps":["trace[1483108019] 'process raft request' (duration: 280.9483ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T23:25:28.505529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.460956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-shpqp\" ","response":"range_response_count:1 size:12336"} {"level":"info","ts":"2026-01-30T23:25:28.506116Z","caller":"traceutil/trace.go:171","msg":"trace[134208167] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-shpqp; range_end:; response_count:1; response_revision:996; }","duration":"190.593058ms","start":"2026-01-30T23:25:28.315003Z","end":"2026-01-30T23:25:28.505596Z","steps":["trace[134208167] 'range keys from in-memory index tree' (duration: 190.248931ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T23:25:50.288443Z","caller":"traceutil/trace.go:171","msg":"trace[331539597] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"128.688002ms","start":"2026-01-30T23:25:50.159741Z","end":"2026-01-30T23:25:50.288429Z","steps":["trace[331539597] 'process raft request' (duration: 128.426157ms)"],"step_count":1}