{"level":"warn","ts":"2026-02-18T10:01:06.324217Z","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-18T10:01:06.324481Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.132:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.132:2380","--initial-cluster=instance=https://199.19.213.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.132: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-18T10:01:06.324646Z","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-18T10:01:06.324722Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-02-18T10:01:06.324862Z","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-18T10:01:06.331998Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"]} {"level":"info","ts":"2026-02-18T10:01:06.332366Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.132: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-18T10:01:06.337358Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.114337ms"} {"level":"info","ts":"2026-02-18T10:01:06.349225Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"19a81b20c4b111c3","cluster-id":"42a0cab9df6b0eb"} {"level":"info","ts":"2026-02-18T10:01:06.349297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T10:01:06.349318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 0"} {"level":"info","ts":"2026-02-18T10:01:06.34933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 19a81b20c4b111c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T10:01:06.349336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became follower at term 1"} {"level":"info","ts":"2026-02-18T10:01:06.349365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"warn","ts":"2026-02-18T10:01:06.360772Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T10:01:06.362556Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T10:01:06.369616Z","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-18T10:01:06.372484Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"19a81b20c4b111c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T10:01:06.38911Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"19a81b20c4b111c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T10:01:06.38919Z","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-18T10:01:06.389227Z","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-18T10:01:06.389252Z","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-18T10:01:06.389913Z","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-18T10:01:06.390926Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"19a81b20c4b111c3","initial-advertise-peer-urls":["https://199.19.213.132:2380"],"listen-peer-urls":["https://199.19.213.132:2380"],"advertise-client-urls":["https://199.19.213.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T10:01:06.390955Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T10:01:06.391024Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-02-18T10:01:06.391032Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.132:2380"} {"level":"info","ts":"2026-02-18T10:01:06.392933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 switched to configuration voters=(1848757474588430787)"} {"level":"info","ts":"2026-02-18T10:01:06.393009Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","added-peer-id":"19a81b20c4b111c3","added-peer-peer-urls":["https://199.19.213.132:2380"]} {"level":"info","ts":"2026-02-18T10:01:06.649705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T10:01:06.64989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T10:01:06.649944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgPreVoteResp from 19a81b20c4b111c3 at term 1"} {"level":"info","ts":"2026-02-18T10:01:06.650003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became candidate at term 2"} {"level":"info","ts":"2026-02-18T10:01:06.650067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 received MsgVoteResp from 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-02-18T10:01:06.650135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"19a81b20c4b111c3 became leader at term 2"} {"level":"info","ts":"2026-02-18T10:01:06.65018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 19a81b20c4b111c3 elected leader 19a81b20c4b111c3 at term 2"} {"level":"info","ts":"2026-02-18T10:01:06.651547Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"19a81b20c4b111c3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.132:2379]}","request-path":"/0/members/19a81b20c4b111c3/attributes","cluster-id":"42a0cab9df6b0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T10:01:06.651738Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T10:01:06.652118Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:01:06.65225Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T10:01:06.652485Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T10:01:06.652568Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T10:01:06.653877Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T10:01:06.654381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.132:2379"} {"level":"info","ts":"2026-02-18T10:01:06.654698Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"42a0cab9df6b0eb","local-member-id":"19a81b20c4b111c3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:01:06.654819Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:01:06.654923Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T10:01:08.489702Z","caller":"traceutil/trace.go:171","msg":"trace[54261180] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"204.039937ms","start":"2026-02-18T10:01:08.285631Z","end":"2026-02-18T10:01:08.489671Z","steps":["trace[54261180] 'process raft request' (duration: 142.586152ms)","trace[54261180] 'compare' (duration: 61.106562ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:01:08.490851Z","caller":"traceutil/trace.go:171","msg":"trace[1177009415] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:7; }","duration":"195.939363ms","start":"2026-02-18T10:01:08.293554Z","end":"2026-02-18T10:01:08.489493Z","steps":["trace[1177009415] 'read index received' (duration: 134.71782ms)","trace[1177009415] 'applied index is now lower than readState.Index' (duration: 61.220763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:01:08.491238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.38625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-18T10:01:08.491419Z","caller":"traceutil/trace.go:171","msg":"trace[1332691404] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:5; }","duration":"205.529001ms","start":"2026-02-18T10:01:08.285789Z","end":"2026-02-18T10:01:08.491318Z","steps":["trace[1332691404] 'agreement among raft nodes before linearized reading' (duration: 205.279539ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.52607Z","caller":"traceutil/trace.go:171","msg":"trace[150238238] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"229.992346ms","start":"2026-02-18T10:01:08.296058Z","end":"2026-02-18T10:01:08.52605Z","steps":["trace[150238238] 'process raft request' (duration: 229.555652ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.526597Z","caller":"traceutil/trace.go:171","msg":"trace[813487560] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"207.410038ms","start":"2026-02-18T10:01:08.319118Z","end":"2026-02-18T10:01:08.526528Z","steps":["trace[813487560] 'process raft request' (duration: 206.56435ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.528509Z","caller":"traceutil/trace.go:171","msg":"trace[679516117] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"207.312897ms","start":"2026-02-18T10:01:08.321185Z","end":"2026-02-18T10:01:08.528497Z","steps":["trace[679516117] 'process raft request' (duration: 204.522031ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.528796Z","caller":"traceutil/trace.go:171","msg":"trace[2056899992] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"207.507849ms","start":"2026-02-18T10:01:08.321276Z","end":"2026-02-18T10:01:08.528784Z","steps":["trace[2056899992] 'process raft request' (duration: 204.463461ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.529272Z","caller":"traceutil/trace.go:171","msg":"trace[1381888798] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"207.857013ms","start":"2026-02-18T10:01:08.321404Z","end":"2026-02-18T10:01:08.529261Z","steps":["trace[1381888798] 'process raft request' (duration: 204.370911ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.530266Z","caller":"traceutil/trace.go:171","msg":"trace[1235730500] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"208.6409ms","start":"2026-02-18T10:01:08.321613Z","end":"2026-02-18T10:01:08.530254Z","steps":["trace[1235730500] 'process raft request' (duration: 204.182809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:08.531309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.370085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-18T10:01:08.531886Z","caller":"traceutil/trace.go:171","msg":"trace[438881688] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"197.974161ms","start":"2026-02-18T10:01:08.333904Z","end":"2026-02-18T10:01:08.531878Z","steps":["trace[438881688] 'agreement among raft nodes before linearized reading' (duration: 197.342485ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.530511Z","caller":"traceutil/trace.go:171","msg":"trace[887627785] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"208.767332ms","start":"2026-02-18T10:01:08.321727Z","end":"2026-02-18T10:01:08.530494Z","steps":["trace[887627785] 'process raft request' (duration: 204.086738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:08.531451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.156028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-18T10:01:08.532409Z","caller":"traceutil/trace.go:171","msg":"trace[1993805933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"217.150187ms","start":"2026-02-18T10:01:08.315245Z","end":"2026-02-18T10:01:08.532395Z","steps":["trace[1993805933] 'agreement among raft nodes before linearized reading' (duration: 216.163147ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:08.53176Z","caller":"traceutil/trace.go:171","msg":"trace[824885003] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"209.069873ms","start":"2026-02-18T10:01:08.322684Z","end":"2026-02-18T10:01:08.531753Z","steps":["trace[824885003] 'process raft request' (duration: 203.154478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:08.531841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.73702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T10:01:08.53287Z","caller":"traceutil/trace.go:171","msg":"trace[749105051] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"196.74936ms","start":"2026-02-18T10:01:08.336072Z","end":"2026-02-18T10:01:08.532821Z","steps":["trace[749105051] 'agreement among raft nodes before linearized reading' (duration: 195.74986ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:14.40097Z","caller":"traceutil/trace.go:171","msg":"trace[1551079060] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:324; }","duration":"111.374884ms","start":"2026-02-18T10:01:14.289571Z","end":"2026-02-18T10:01:14.400946Z","steps":["trace[1551079060] 'read index received' (duration: 111.157552ms)","trace[1551079060] 'applied index is now lower than readState.Index' (duration: 215.972µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:01:14.401176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.576086ms","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-18T10:01:14.401198Z","caller":"traceutil/trace.go:171","msg":"trace[923234125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:317; }","duration":"111.676277ms","start":"2026-02-18T10:01:14.289513Z","end":"2026-02-18T10:01:14.40119Z","steps":["trace[923234125] 'agreement among raft nodes before linearized reading' (duration: 111.563506ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:14.401473Z","caller":"traceutil/trace.go:171","msg":"trace[407481438] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"632.716144ms","start":"2026-02-18T10:01:13.76875Z","end":"2026-02-18T10:01:14.401466Z","steps":["trace[407481438] 'process raft request' (duration: 632.046578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:14.40181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:01:13.768718Z","time spent":"632.778374ms","remote":"127.0.0.1:37826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T10:01:15.114873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.990206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T10:01:15.11495Z","caller":"traceutil/trace.go:171","msg":"trace[568420807] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"704.896741ms","start":"2026-02-18T10:01:14.41004Z","end":"2026-02-18T10:01:15.114937Z","steps":["trace[568420807] 'read index received' (duration: 427.564341ms)","trace[568420807] 'applied index is now lower than readState.Index' (duration: 277.3313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:01:15.115029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.991412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-18T10:01:15.115051Z","caller":"traceutil/trace.go:171","msg":"trace[1773375235] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:318; }","duration":"705.074033ms","start":"2026-02-18T10:01:14.409971Z","end":"2026-02-18T10:01:15.115045Z","steps":["trace[1773375235] 'agreement among raft nodes before linearized reading' (duration: 704.997552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:15.115074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:01:14.409949Z","time spent":"705.119143ms","remote":"127.0.0.1:37740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-18T10:01:15.115324Z","caller":"traceutil/trace.go:171","msg":"trace[1791719343] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"705.415526ms","start":"2026-02-18T10:01:14.409899Z","end":"2026-02-18T10:01:15.115315Z","steps":["trace[1791719343] 'process raft request' (duration: 427.743644ms)","trace[1791719343] 'compare' (duration: 276.888335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:01:15.115372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:01:14.409884Z","time spent":"705.465207ms","remote":"127.0.0.1:37874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:01:21.030502Z","caller":"traceutil/trace.go:171","msg":"trace[1299814185] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"165.060212ms","start":"2026-02-18T10:01:20.865106Z","end":"2026-02-18T10:01:21.030166Z","steps":["trace[1299814185] 'process raft request' (duration: 164.869831ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:37.567184Z","caller":"traceutil/trace.go:171","msg":"trace[1451185943] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"233.062045ms","start":"2026-02-18T10:01:37.334099Z","end":"2026-02-18T10:01:37.567161Z","steps":["trace[1451185943] 'read index received' (duration: 208.089034ms)","trace[1451185943] 'applied index is now lower than readState.Index' (duration: 24.971531ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:01:37.567332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.214377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3864"} {"level":"info","ts":"2026-02-18T10:01:37.567779Z","caller":"traceutil/trace.go:171","msg":"trace[1195577849] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:504; }","duration":"233.686759ms","start":"2026-02-18T10:01:37.334077Z","end":"2026-02-18T10:01:37.567764Z","steps":["trace[1195577849] 'agreement among raft nodes before linearized reading' (duration: 233.180436ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:01:37.568251Z","caller":"traceutil/trace.go:171","msg":"trace[912610040] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"259.540977ms","start":"2026-02-18T10:01:37.308694Z","end":"2026-02-18T10:01:37.568235Z","steps":["trace[912610040] 'process raft request' (duration: 233.561489ms)","trace[912610040] 'compare' (duration: 24.570738ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:01:43.485957Z","caller":"traceutil/trace.go:171","msg":"trace[178058430] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"160.524843ms","start":"2026-02-18T10:01:43.3252Z","end":"2026-02-18T10:01:43.485725Z","steps":["trace[178058430] 'process raft request' (duration: 160.394678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:43.999676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.240579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T10:01:43.99973Z","caller":"traceutil/trace.go:171","msg":"trace[1138580877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"161.311782ms","start":"2026-02-18T10:01:43.838405Z","end":"2026-02-18T10:01:43.999716Z","steps":["trace[1138580877] 'range keys from in-memory index tree' (duration: 161.145296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:43.999881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.302526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-18T10:01:43.999923Z","caller":"traceutil/trace.go:171","msg":"trace[578722133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"163.373788ms","start":"2026-02-18T10:01:43.836539Z","end":"2026-02-18T10:01:43.999913Z","steps":["trace[578722133] 'range keys from in-memory index tree' (duration: 163.182571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:45.500457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1280038724796428698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T10:01:45.610563Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.078553887s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T10:01:45.610865Z","caller":"traceutil/trace.go:171","msg":"trace[613543390] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"611.178086ms","start":"2026-02-18T10:01:44.999669Z","end":"2026-02-18T10:01:45.610847Z","steps":["trace[613543390] 'read index received' (duration: 610.998841ms)","trace[613543390] 'applied index is now lower than readState.Index' (duration: 178.505µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T10:01:45.611038Z","caller":"traceutil/trace.go:171","msg":"trace[1271970486] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.079125487s","start":"2026-02-18T10:01:44.531905Z","end":"2026-02-18T10:01:45.61103Z","steps":["trace[1271970486] 'process raft request' (duration: 1.078814466s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:45.611108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:01:44.531885Z","time spent":"1.079171668s","remote":"127.0.0.1:37806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T10:01:45.611224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.561539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:01:45.611243Z","caller":"traceutil/trace.go:171","msg":"trace[1757637537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"611.606131ms","start":"2026-02-18T10:01:44.999631Z","end":"2026-02-18T10:01:45.611237Z","steps":["trace[1757637537] 'agreement among raft nodes before linearized reading' (duration: 611.57419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:45.611262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:01:44.999601Z","time spent":"611.657603ms","remote":"127.0.0.1:37622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T10:01:45.611396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.457183ms","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-18T10:01:45.611414Z","caller":"traceutil/trace.go:171","msg":"trace[1976473810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"122.500014ms","start":"2026-02-18T10:01:45.488908Z","end":"2026-02-18T10:01:45.611408Z","steps":["trace[1976473810] 'agreement among raft nodes before linearized reading' (duration: 122.431322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:45.993901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.424635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T10:01:45.994229Z","caller":"traceutil/trace.go:171","msg":"trace[1578634621] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"371.888481ms","start":"2026-02-18T10:01:45.622316Z","end":"2026-02-18T10:01:45.994205Z","steps":["trace[1578634621] 'process raft request' (duration: 110.092692ms)","trace[1578634621] 'compare' (duration: 261.360273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:01:45.99434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T10:01:45.622307Z","time spent":"371.974423ms","remote":"127.0.0.1:37874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T10:01:45.994535Z","caller":"traceutil/trace.go:171","msg":"trace[285016756] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"261.277968ms","start":"2026-02-18T10:01:45.733249Z","end":"2026-02-18T10:01:45.994527Z","steps":["trace[285016756] 'read index received' (duration: 19.25µs)","trace[285016756] 'applied index is now lower than readState.Index' (duration: 261.257928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T10:01:45.99916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.903235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-18T10:01:45.999197Z","caller":"traceutil/trace.go:171","msg":"trace[1718385429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"265.969888ms","start":"2026-02-18T10:01:45.733218Z","end":"2026-02-18T10:01:45.999188Z","steps":["trace[1718385429] 'agreement among raft nodes before linearized reading' (duration: 261.333641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T10:01:47.966633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.775909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T10:01:47.966716Z","caller":"traceutil/trace.go:171","msg":"trace[1139265503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:564; }","duration":"216.895543ms","start":"2026-02-18T10:01:47.74981Z","end":"2026-02-18T10:01:47.966705Z","steps":["trace[1139265503] 'range keys from in-memory index tree' (duration: 216.727498ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:03:16.8217Z","caller":"traceutil/trace.go:171","msg":"trace[1018138964] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"104.376352ms","start":"2026-02-18T10:03:16.7173Z","end":"2026-02-18T10:03:16.821677Z","steps":["trace[1018138964] 'process raft request' (duration: 103.815013ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T10:03:16.822793Z","caller":"traceutil/trace.go:171","msg":"trace[336881149] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"105.058403ms","start":"2026-02-18T10:03:16.71772Z","end":"2026-02-18T10:03:16.822778Z","steps":["trace[336881149] 'process raft request' (duration: 103.64972ms)"],"step_count":1}