{"level":"warn","ts":"2026-02-13T14:18:04.175608Z","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-13T14:18:04.175725Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-13T14:18:04.175799Z","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-13T14:18:04.175809Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-13T14:18:04.175829Z","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-13T14:18:04.177945Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-13T14:18:04.180826Z","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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-13T14:18:04.188782Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.682071ms"} {"level":"info","ts":"2026-02-13T14:18:04.197859Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-13T14:18:04.19791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T14:18:04.197929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-13T14:18:04.197939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T14:18:04.197948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-13T14:18:04.198044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-13T14:18:04.211781Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T14:18:04.227983Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T14:18:04.238995Z","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-13T14:18:04.244148Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T14:18:04.244812Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T14:18:04.245338Z","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-13T14:18:04.245399Z","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-13T14:18:04.245409Z","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-13T14:18:04.246159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-13T14:18:04.246307Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-13T14:18:04.248372Z","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-13T14:18:04.253826Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T14:18:04.25392Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T14:18:04.248533Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-13T14:18:04.255078Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-13T14:18:05.098947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T14:18:05.098989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T14:18:05.099012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-13T14:18:05.099025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-13T14:18:05.09903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-13T14:18:05.099038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-13T14:18:05.099044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-13T14:18:05.102303Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T14:18:05.102429Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:18:05.102569Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:18:05.102928Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:18:05.109551Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-13T14:18:05.116502Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T14:18:05.116572Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T14:18:05.116587Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T14:18:05.123481Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:18:05.125474Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:18:05.125523Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-13T14:18:15.499085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.911311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:18:15.49985Z","caller":"traceutil/trace.go:171","msg":"trace[1824207898] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"303.649514ms","start":"2026-02-13T14:18:15.195902Z","end":"2026-02-13T14:18:15.499552Z","steps":["trace[1824207898] 'process raft request' (duration: 107.480131ms)","trace[1824207898] 'compare' (duration: 194.741569ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:18:15.499941Z","caller":"traceutil/trace.go:171","msg":"trace[609893346] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"303.906696ms","start":"2026-02-13T14:18:15.196027Z","end":"2026-02-13T14:18:15.499933Z","steps":["trace[609893346] 'process raft request' (duration: 303.19653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:15.500009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:15.196021Z","time spent":"303.942636ms","remote":"127.0.0.1:56468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T14:18:15.500178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:15.195888Z","time spent":"304.269099ms","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3401,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:15.500456Z","caller":"traceutil/trace.go:171","msg":"trace[721711712] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:387; }","duration":"276.651103ms","start":"2026-02-13T14:18:15.223796Z","end":"2026-02-13T14:18:15.500447Z","steps":["trace[721711712] 'read index received' (duration: 79.538571ms)","trace[721711712] 'applied index is now lower than readState.Index' (duration: 197.111302ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:15.505568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.765412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-13T14:18:15.505767Z","caller":"traceutil/trace.go:171","msg":"trace[154560184] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:377; }","duration":"282.017363ms","start":"2026-02-13T14:18:15.223741Z","end":"2026-02-13T14:18:15.505758Z","steps":["trace[154560184] 'agreement among raft nodes before linearized reading' (duration: 276.738133ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:18:20.782007Z","caller":"traceutil/trace.go:171","msg":"trace[1180415357] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"105.596951ms","start":"2026-02-13T14:18:20.67639Z","end":"2026-02-13T14:18:20.781987Z","steps":["trace[1180415357] 'read index received' (duration: 105.374709ms)","trace[1180415357] 'applied index is now lower than readState.Index' (duration: 221.322µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:18:20.782552Z","caller":"traceutil/trace.go:171","msg":"trace[572719210] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"121.366648ms","start":"2026-02-13T14:18:20.661172Z","end":"2026-02-13T14:18:20.782539Z","steps":["trace[572719210] 'process raft request' (duration: 120.670161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:20.783494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.069524ms","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-13T14:18:20.783771Z","caller":"traceutil/trace.go:171","msg":"trace[271320276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"107.184234ms","start":"2026-02-13T14:18:20.676346Z","end":"2026-02-13T14:18:20.783531Z","steps":["trace[271320276] 'agreement among raft nodes before linearized reading' (duration: 107.073113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:22.51826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.573012ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:18:22.51838Z","caller":"traceutil/trace.go:171","msg":"trace[1736618978] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:415; }","duration":"593.701943ms","start":"2026-02-13T14:18:21.924659Z","end":"2026-02-13T14:18:22.518361Z","steps":["trace[1736618978] 'range keys from in-memory index tree' (duration: 593.558742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:22.518588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569053775s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:18:22.518797Z","caller":"traceutil/trace.go:171","msg":"trace[2119640869] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"1.71458697s","start":"2026-02-13T14:18:20.804164Z","end":"2026-02-13T14:18:22.518751Z","steps":["trace[2119640869] 'read index received' (duration: 4.823045ms)","trace[2119640869] 'applied index is now lower than readState.Index' (duration: 1.709760694s)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:18:22.519134Z","caller":"traceutil/trace.go:171","msg":"trace[396903797] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.72201361s","start":"2026-02-13T14:18:20.797109Z","end":"2026-02-13T14:18:22.519122Z","steps":["trace[396903797] 'process raft request' (duration: 152.178958ms)","trace[396903797] 'compare' (duration: 1.566573342s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:22.519275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:20.797094Z","time spent":"1.722126771s","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6879,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:18:22.519528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715360056s","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-02-13T14:18:22.519615Z","caller":"traceutil/trace.go:171","msg":"trace[1128899602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"1.715464077s","start":"2026-02-13T14:18:20.804142Z","end":"2026-02-13T14:18:22.519606Z","steps":["trace[1128899602] 'agreement among raft nodes before linearized reading' (duration: 1.715333206s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:22.519699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:20.804133Z","time spent":"1.715558129s","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T14:18:22.519939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665449329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-13T14:18:22.520027Z","caller":"traceutil/trace.go:171","msg":"trace[1429364721] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:416; }","duration":"1.66559757s","start":"2026-02-13T14:18:20.854422Z","end":"2026-02-13T14:18:22.52002Z","steps":["trace[1429364721] 'agreement among raft nodes before linearized reading' (duration: 1.665460139s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:22.520134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:20.854405Z","time spent":"1.665719251s","remote":"127.0.0.1:56836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4058,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-13T14:18:22.520442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.089145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T14:18:22.520554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.723119ms","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-13T14:18:22.520606Z","caller":"traceutil/trace.go:171","msg":"trace[1543883430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"512.81684ms","start":"2026-02-13T14:18:22.007778Z","end":"2026-02-13T14:18:22.520595Z","steps":["trace[1543883430] 'agreement among raft nodes before linearized reading' (duration: 512.673039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:22.52065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:22.007757Z","time spent":"512.884391ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T14:18:22.520561Z","caller":"traceutil/trace.go:171","msg":"trace[1880032034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"995.262766ms","start":"2026-02-13T14:18:21.52529Z","end":"2026-02-13T14:18:22.520553Z","steps":["trace[1880032034] 'agreement among raft nodes before linearized reading' (duration: 995.130854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:22.52079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:21.525269Z","time spent":"995.512518ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:18:23.29715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529151024769445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:18:23.683872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.339298ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:18:23.684054Z","caller":"traceutil/trace.go:171","msg":"trace[499130880] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"1.141628937s","start":"2026-02-13T14:18:22.542381Z","end":"2026-02-13T14:18:23.68401Z","steps":["trace[499130880] 'process raft request' (duration: 982.545673ms)","trace[499130880] 'compare' (duration: 158.192236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:23.684226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:22.542361Z","time spent":"1.141729808s","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:23.685015Z","caller":"traceutil/trace.go:171","msg":"trace[1433287641] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.138221945s","start":"2026-02-13T14:18:22.54678Z","end":"2026-02-13T14:18:23.685002Z","steps":["trace[1433287641] 'process raft request' (duration: 1.13767149s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:23.685107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:22.546768Z","time spent":"1.138299956s","remote":"127.0.0.1:56584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:23.685953Z","caller":"traceutil/trace.go:171","msg":"trace[203881827] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"1.138993843s","start":"2026-02-13T14:18:22.546946Z","end":"2026-02-13T14:18:23.68594Z","steps":["trace[203881827] 'process raft request' (duration: 1.13768526s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:23.686037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:22.54694Z","time spent":"1.139050993s","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:23.686323Z","caller":"traceutil/trace.go:171","msg":"trace[855221533] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.139157355s","start":"2026-02-13T14:18:22.547153Z","end":"2026-02-13T14:18:23.68631Z","steps":["trace[855221533] 'process raft request' (duration: 1.137511579s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:23.686611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:22.547137Z","time spent":"1.139399107s","remote":"127.0.0.1:56836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:23.692622Z","caller":"traceutil/trace.go:171","msg":"trace[1636472482] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:430; }","duration":"896.560057ms","start":"2026-02-13T14:18:22.796052Z","end":"2026-02-13T14:18:23.692612Z","steps":["trace[1636472482] 'read index received' (duration: 728.882893ms)","trace[1636472482] 'applied index is now lower than readState.Index' (duration: 167.676674ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:23.692799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.744398ms","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-13T14:18:23.692816Z","caller":"traceutil/trace.go:171","msg":"trace[1799608740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"896.805278ms","start":"2026-02-13T14:18:22.796006Z","end":"2026-02-13T14:18:23.692811Z","steps":["trace[1799608740] 'agreement among raft nodes before linearized reading' (duration: 896.772608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:23.692829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:22.795885Z","time spent":"896.94045ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T14:18:23.692898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.224536ms","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-13T14:18:23.692909Z","caller":"traceutil/trace.go:171","msg":"trace[514181924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"879.256316ms","start":"2026-02-13T14:18:22.813649Z","end":"2026-02-13T14:18:23.692906Z","steps":["trace[514181924] 'agreement among raft nodes before linearized reading' (duration: 879.235826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:23.692921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:22.813636Z","time spent":"879.281577ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T14:18:23.693068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.668383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:18:23.693083Z","caller":"traceutil/trace.go:171","msg":"trace[20514387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"168.722233ms","start":"2026-02-13T14:18:23.524357Z","end":"2026-02-13T14:18:23.693079Z","steps":["trace[20514387] 'agreement among raft nodes before linearized reading' (duration: 168.696433ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:18:28.061265Z","caller":"traceutil/trace.go:171","msg":"trace[298160151] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"323.973955ms","start":"2026-02-13T14:18:27.737262Z","end":"2026-02-13T14:18:28.061236Z","steps":["trace[298160151] 'read index received' (duration: 323.796263ms)","trace[298160151] 'applied index is now lower than readState.Index' (duration: 176.872µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:18:28.061386Z","caller":"traceutil/trace.go:171","msg":"trace[1198742401] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"341.765466ms","start":"2026-02-13T14:18:27.71961Z","end":"2026-02-13T14:18:28.061376Z","steps":["trace[1198742401] 'process raft request' (duration: 341.466993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:28.061487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:27.7196Z","time spent":"341.821506ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:18:28.061515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.754054ms","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-13T14:18:28.061565Z","caller":"traceutil/trace.go:171","msg":"trace[1934476178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"323.811414ms","start":"2026-02-13T14:18:27.737738Z","end":"2026-02-13T14:18:28.061549Z","steps":["trace[1934476178] 'agreement among raft nodes before linearized reading' (duration: 323.736174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:28.061598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:27.737733Z","time spent":"323.858125ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T14:18:28.06172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.45439ms","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-13T14:18:28.061747Z","caller":"traceutil/trace.go:171","msg":"trace[1467970523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"324.49524ms","start":"2026-02-13T14:18:27.737243Z","end":"2026-02-13T14:18:28.061738Z","steps":["trace[1467970523] 'agreement among raft nodes before linearized reading' (duration: 324.426079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:28.061766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:27.737235Z","time spent":"324.52716ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-13T14:18:37.572757Z","caller":"traceutil/trace.go:171","msg":"trace[1829301851] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"225.3831ms","start":"2026-02-13T14:18:37.347356Z","end":"2026-02-13T14:18:37.572739Z","steps":["trace[1829301851] 'process raft request' (duration: 225.291599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:37.970197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.80036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T14:18:37.970219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.221493ms","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-13T14:18:37.970266Z","caller":"traceutil/trace.go:171","msg":"trace[8743424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"377.897401ms","start":"2026-02-13T14:18:37.59235Z","end":"2026-02-13T14:18:37.970247Z","steps":["trace[8743424] 'range keys from in-memory index tree' (duration: 377.755109ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:18:37.970284Z","caller":"traceutil/trace.go:171","msg":"trace[29464973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"221.293563ms","start":"2026-02-13T14:18:37.748976Z","end":"2026-02-13T14:18:37.97027Z","steps":["trace[29464973] 'range keys from in-memory index tree' (duration: 221.171453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:37.970303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:37.592337Z","time spent":"377.957101ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:18:37.970393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.460546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-13T14:18:37.970414Z","caller":"traceutil/trace.go:171","msg":"trace[622018624] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:514; }","duration":"156.480986ms","start":"2026-02-13T14:18:37.813926Z","end":"2026-02-13T14:18:37.970407Z","steps":["trace[622018624] 'range keys from in-memory index tree' (duration: 156.419736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:44.023662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529151024769704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:18:44.616226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.006573ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T14:18:44.616326Z","caller":"traceutil/trace.go:171","msg":"trace[1512870336] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"1.093387616s","start":"2026-02-13T14:18:43.522919Z","end":"2026-02-13T14:18:44.616307Z","steps":["trace[1512870336] 'read index received' (duration: 599.233987ms)","trace[1512870336] 'applied index is now lower than readState.Index' (duration: 494.152339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:44.616435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093504401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:18:44.616462Z","caller":"traceutil/trace.go:171","msg":"trace[495904634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"1.093564784s","start":"2026-02-13T14:18:43.522887Z","end":"2026-02-13T14:18:44.616452Z","steps":["trace[495904634] 'agreement among raft nodes before linearized reading' (duration: 1.093507831s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:44.616541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:43.522871Z","time spent":"1.093661768s","remote":"127.0.0.1:56412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:18:44.616889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.073267ms","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-13T14:18:44.616929Z","caller":"traceutil/trace.go:171","msg":"trace[343793346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"450.124878ms","start":"2026-02-13T14:18:44.166793Z","end":"2026-02-13T14:18:44.616918Z","steps":["trace[343793346] 'agreement among raft nodes before linearized reading' (duration: 449.998113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:44.616931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.425032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-13T14:18:44.616956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.166786Z","time spent":"450.163931ms","remote":"127.0.0.1:56654","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-13T14:18:44.616981Z","caller":"traceutil/trace.go:171","msg":"trace[890102679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"603.519187ms","start":"2026-02-13T14:18:44.013449Z","end":"2026-02-13T14:18:44.616968Z","steps":["trace[890102679] 'agreement among raft nodes before linearized reading' (duration: 603.343719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:44.61701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.013433Z","time spent":"603.573078ms","remote":"127.0.0.1:56654","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-13T14:18:44.617251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.420421ms","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-13T14:18:44.617283Z","caller":"traceutil/trace.go:171","msg":"trace[1113090551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"494.478433ms","start":"2026-02-13T14:18:44.122794Z","end":"2026-02-13T14:18:44.617273Z","steps":["trace[1113090551] 'agreement among raft nodes before linearized reading' (duration: 494.394719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:44.61731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.122779Z","time spent":"494.523415ms","remote":"127.0.0.1:56654","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-02-13T14:18:44.617335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.389846ms","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-13T14:18:44.617352Z","caller":"traceutil/trace.go:171","msg":"trace[65160502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"455.438087ms","start":"2026-02-13T14:18:44.161909Z","end":"2026-02-13T14:18:44.617347Z","steps":["trace[65160502] 'agreement among raft nodes before linearized reading' (duration: 455.365284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:44.617367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.161892Z","time spent":"455.472149ms","remote":"127.0.0.1:56654","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-13T14:18:45.13064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529151024769707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:18:45.631263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529151024769707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:18:46.132397Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529151024769707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:18:46.630821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000247731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T14:18:46.630879Z","caller":"traceutil/trace.go:171","msg":"trace[1540852841] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000350796s","start":"2026-02-13T14:18:44.630516Z","end":"2026-02-13T14:18:46.630867Z","steps":["trace[1540852841] 'agreement among raft nodes before linearized reading' (duration: 2.000268162s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:46.630923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.630484Z","time spent":"2.000433629s","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:18:46.63251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529151024769707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:18:46.865897Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.12589227s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T14:18:46.866122Z","caller":"traceutil/trace.go:171","msg":"trace[1954431422] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"2.245294198s","start":"2026-02-13T14:18:44.620812Z","end":"2026-02-13T14:18:46.866106Z","steps":["trace[1954431422] 'process raft request' (duration: 2.245209994s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:46.866196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.620797Z","time spent":"2.245349021s","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:18:48.631907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000406114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-13T14:18:48.631987Z","caller":"traceutil/trace.go:171","msg":"trace[112138780] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00052258s","start":"2026-02-13T14:18:46.631446Z","end":"2026-02-13T14:18:48.631968Z","steps":["trace[112138780] 'agreement among raft nodes before linearized reading' (duration: 2.000417575s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:48.632085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:46.631436Z","time spent":"2.000632964s","remote":"127.0.0.1:56412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:18:48.674911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.717767793s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:18:48.675076Z","caller":"traceutil/trace.go:171","msg":"trace[113873964] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"920.56948ms","start":"2026-02-13T14:18:47.754495Z","end":"2026-02-13T14:18:48.675065Z","steps":["trace[113873964] 'process raft request' (duration: 920.546809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:48.675119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:47.754478Z","time spent":"920.619851ms","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:48.6753Z","caller":"traceutil/trace.go:171","msg":"trace[288307259] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"4.047348597s","start":"2026-02-13T14:18:44.627942Z","end":"2026-02-13T14:18:48.675291Z","steps":["trace[288307259] 'process raft request' (duration: 2.329155137s)","trace[288307259] 'compare' (duration: 1.717087413s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:48.675333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.627936Z","time spent":"4.047379438s","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:48.675403Z","caller":"traceutil/trace.go:171","msg":"trace[848078439] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"4.0474095s","start":"2026-02-13T14:18:44.627989Z","end":"2026-02-13T14:18:48.675398Z","steps":["trace[848078439] 'process raft request' (duration: 4.046985442s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:48.675433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.627985Z","time spent":"4.047432351s","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:48.675501Z","caller":"traceutil/trace.go:171","msg":"trace[277396541] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"4.04609491s","start":"2026-02-13T14:18:44.629399Z","end":"2026-02-13T14:18:48.675494Z","steps":["trace[277396541] 'process raft request' (duration: 4.045617039s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:48.675529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:44.629393Z","time spent":"4.046121111s","remote":"127.0.0.1:56654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:18:48.675597Z","caller":"traceutil/trace.go:171","msg":"trace[314431950] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:575; }","duration":"4.045039352s","start":"2026-02-13T14:18:44.630551Z","end":"2026-02-13T14:18:48.675591Z","steps":["trace[314431950] 'read index received' (duration: 2.235567181s)","trace[314431950] 'applied index is now lower than readState.Index' (duration: 1.809471421s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:48.700788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.178842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T14:18:48.700853Z","caller":"traceutil/trace.go:171","msg":"trace[278390796] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:561; }","duration":"738.274007ms","start":"2026-02-13T14:18:47.962556Z","end":"2026-02-13T14:18:48.70083Z","steps":["trace[278390796] 'agreement among raft nodes before linearized reading' (duration: 738.140481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:48.700892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:47.962536Z","time spent":"738.34851ms","remote":"127.0.0.1:56436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-13T14:18:51.086756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.167867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:18:51.086813Z","caller":"traceutil/trace.go:171","msg":"trace[859643961] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"561.779529ms","start":"2026-02-13T14:18:50.525025Z","end":"2026-02-13T14:18:51.086804Z","steps":["trace[859643961] 'read index received' (duration: 33.602µs)","trace[859643961] 'applied index is now lower than readState.Index' (duration: 561.745197ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:18:51.086848Z","caller":"traceutil/trace.go:171","msg":"trace[541221327] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"753.045518ms","start":"2026-02-13T14:18:50.333798Z","end":"2026-02-13T14:18:51.086843Z","steps":["trace[541221327] 'process raft request' (duration: 103.712454ms)","trace[541221327] 'compare' (duration: 648.98476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:51.086871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:50.333782Z","time spent":"753.07757ms","remote":"127.0.0.1:56468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":834,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T14:18:51.086987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.959336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:18:51.087Z","caller":"traceutil/trace.go:171","msg":"trace[1365862959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"562.009868ms","start":"2026-02-13T14:18:50.524985Z","end":"2026-02-13T14:18:51.086995Z","steps":["trace[1365862959] 'agreement among raft nodes before linearized reading' (duration: 561.972636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:51.087012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:50.524963Z","time spent":"562.047149ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:18:51.087435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.26522ms","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-13T14:18:51.087452Z","caller":"traceutil/trace.go:171","msg":"trace[1546993043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"407.28287ms","start":"2026-02-13T14:18:50.680164Z","end":"2026-02-13T14:18:51.087447Z","steps":["trace[1546993043] 'agreement among raft nodes before linearized reading' (duration: 407.215547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:51.087464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:50.680126Z","time spent":"407.335353ms","remote":"127.0.0.1:56654","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":"info","ts":"2026-02-13T14:18:51.087573Z","caller":"traceutil/trace.go:171","msg":"trace[1481972713] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"220.53469ms","start":"2026-02-13T14:18:50.867033Z","end":"2026-02-13T14:18:51.087568Z","steps":["trace[1481972713] 'process raft request' (duration: 220.312451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:51.087634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.798162ms","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-13T14:18:51.087646Z","caller":"traceutil/trace.go:171","msg":"trace[537320872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"210.832413ms","start":"2026-02-13T14:18:50.876809Z","end":"2026-02-13T14:18:51.087642Z","steps":["trace[537320872] 'agreement among raft nodes before linearized reading' (duration: 210.809312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:51.087738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.352362ms","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-13T14:18:51.08775Z","caller":"traceutil/trace.go:171","msg":"trace[2077714859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"406.376803ms","start":"2026-02-13T14:18:50.68137Z","end":"2026-02-13T14:18:51.087746Z","steps":["trace[2077714859] 'agreement among raft nodes before linearized reading' (duration: 406.341831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:51.087761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:50.681365Z","time spent":"406.394103ms","remote":"127.0.0.1:56654","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-13T14:18:51.087832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.476967ms","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-13T14:18:51.087842Z","caller":"traceutil/trace.go:171","msg":"trace[1740118548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"406.506778ms","start":"2026-02-13T14:18:50.681332Z","end":"2026-02-13T14:18:51.087839Z","steps":["trace[1740118548] 'agreement among raft nodes before linearized reading' (duration: 406.473397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:51.087853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:18:50.681322Z","time spent":"406.528099ms","remote":"127.0.0.1:56654","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-13T14:18:53.639112Z","caller":"traceutil/trace.go:171","msg":"trace[1594331019] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"225.212723ms","start":"2026-02-13T14:18:53.41389Z","end":"2026-02-13T14:18:53.639103Z","steps":["trace[1594331019] 'process raft request' (duration: 225.024916ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:18:53.639005Z","caller":"traceutil/trace.go:171","msg":"trace[320033138] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"187.309845ms","start":"2026-02-13T14:18:53.451682Z","end":"2026-02-13T14:18:53.638991Z","steps":["trace[320033138] 'read index received' (duration: 187.181349ms)","trace[320033138] 'applied index is now lower than readState.Index' (duration: 127.986µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:18:53.639351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.580609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:18:53.639368Z","caller":"traceutil/trace.go:171","msg":"trace[1502254912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"110.637283ms","start":"2026-02-13T14:18:53.528725Z","end":"2026-02-13T14:18:53.639362Z","steps":["trace[1502254912] 'agreement among raft nodes before linearized reading' (duration: 110.601111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:18:53.639499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.833796ms","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-13T14:18:53.639521Z","caller":"traceutil/trace.go:171","msg":"trace[1045897719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:628; }","duration":"187.888217ms","start":"2026-02-13T14:18:53.45162Z","end":"2026-02-13T14:18:53.639509Z","steps":["trace[1045897719] 'agreement among raft nodes before linearized reading' (duration: 187.671179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:20:14.418738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.284116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:20:14.418837Z","caller":"traceutil/trace.go:171","msg":"trace[55482466] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:991; }","duration":"143.442579ms","start":"2026-02-13T14:20:14.275376Z","end":"2026-02-13T14:20:14.418819Z","steps":["trace[55482466] 'count revisions from in-memory index tree' (duration: 143.147593ms)"],"step_count":1}