{"level":"warn","ts":"2026-02-12T03:34:45.229232Z","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-12T03:34:45.23042Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-12T03:34:45.230716Z","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-12T03:34:45.232919Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-12T03:34:45.232981Z","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-12T03:34:45.238356Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-02-12T03:34:45.242332Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-12T03:34:45.293983Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"51.328454ms"} {"level":"info","ts":"2026-02-12T03:34:45.525713Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-02-12T03:34:45.525793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T03:34:45.525816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-02-12T03:34:45.525824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T03:34:45.525836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-02-12T03:34:45.52587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-02-12T03:34:45.549468Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T03:34:45.583912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T03:34:45.613404Z","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-12T03:34:45.64844Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T03:34:45.650671Z","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-12T03:34:45.650894Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T03:34:45.650918Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T03:34:45.651119Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"warn","ts":"2026-02-12T03:34:45.651174Z","logger":"raft","caller":"etcdserver/zap_raft.go:85","msg":"90a0cb2044183f95 cannot campaign at term 1 since there are still 1 pending configuration changes to apply"} {"level":"info","ts":"2026-02-12T03:34:45.651284Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-12T03:34:45.651304Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-02-12T03:34:45.652178Z","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-12T03:34:45.652213Z","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-12T03:34:45.652221Z","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-12T03:34:45.667397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-02-12T03:34:45.667569Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-02-12T03:34:46.626882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T03:34:46.626968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T03:34:46.627054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-02-12T03:34:46.627085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-02-12T03:34:46.627105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-12T03:34:46.627131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-02-12T03:34:46.627153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-02-12T03:34:46.629127Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T03:34:46.629468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:34:46.629625Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:34:46.63036Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T03:34:46.630399Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T03:34:46.63044Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:34:46.630532Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:34:46.630564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:34:46.630605Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:34:46.633847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-02-12T03:34:46.638433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T03:34:51.192842Z","caller":"traceutil/trace.go:171","msg":"trace[561295080] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"180.307314ms","start":"2026-02-12T03:34:51.012487Z","end":"2026-02-12T03:34:51.192794Z","steps":["trace[561295080] 'process raft request' (duration: 96.435616ms)","trace[561295080] 'compare' (duration: 83.469176ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:51.1927Z","caller":"traceutil/trace.go:171","msg":"trace[1733409122] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"179.133329ms","start":"2026-02-12T03:34:51.013538Z","end":"2026-02-12T03:34:51.192671Z","steps":["trace[1733409122] 'process raft request' (duration: 179.042829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:51.747113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.891276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:34:51.747515Z","caller":"traceutil/trace.go:171","msg":"trace[709729372] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"194.914821ms","start":"2026-02-12T03:34:51.552432Z","end":"2026-02-12T03:34:51.747346Z","steps":["trace[709729372] 'process raft request' (duration: 63.001761ms)","trace[709729372] 'compare' (duration: 130.586205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:34:52.472725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.772306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:34:52.472805Z","caller":"traceutil/trace.go:171","msg":"trace[765437167] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:284; }","duration":"187.907476ms","start":"2026-02-12T03:34:52.28488Z","end":"2026-02-12T03:34:52.472788Z","steps":["trace[765437167] 'range keys from in-memory index tree' (duration: 187.702556ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:52.476003Z","caller":"traceutil/trace.go:171","msg":"trace[108155305] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"174.956316ms","start":"2026-02-12T03:34:52.301038Z","end":"2026-02-12T03:34:52.475994Z","steps":["trace[108155305] 'process raft request' (duration: 174.785285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:52.916602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.258144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:34:52.916715Z","caller":"traceutil/trace.go:171","msg":"trace[1849628435] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"212.62459ms","start":"2026-02-12T03:34:52.704071Z","end":"2026-02-12T03:34:52.916696Z","steps":["trace[1849628435] 'process raft request' (duration: 104.991573ms)","trace[1849628435] 'compare' (duration: 107.145774ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:52.922787Z","caller":"traceutil/trace.go:171","msg":"trace[205903277] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"141.938845ms","start":"2026-02-12T03:34:52.780831Z","end":"2026-02-12T03:34:52.922769Z","steps":["trace[205903277] 'process raft request' (duration: 141.864704ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:52.923056Z","caller":"traceutil/trace.go:171","msg":"trace[1014683962] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"216.485278ms","start":"2026-02-12T03:34:52.706561Z","end":"2026-02-12T03:34:52.923046Z","steps":["trace[1014683962] 'process raft request' (duration: 215.966296ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:52.923417Z","caller":"traceutil/trace.go:171","msg":"trace[1820595124] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"141.906524ms","start":"2026-02-12T03:34:52.781504Z","end":"2026-02-12T03:34:52.923411Z","steps":["trace[1820595124] 'process raft request' (duration: 141.255892ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:52.923651Z","caller":"traceutil/trace.go:171","msg":"trace[1463154519] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"142.708657ms","start":"2026-02-12T03:34:52.780936Z","end":"2026-02-12T03:34:52.923644Z","steps":["trace[1463154519] 'process raft request' (duration: 141.791174ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:52.923874Z","caller":"traceutil/trace.go:171","msg":"trace[1449038025] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"142.287516ms","start":"2026-02-12T03:34:52.78158Z","end":"2026-02-12T03:34:52.923867Z","steps":["trace[1449038025] 'process raft request' (duration: 141.200512ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:52.924026Z","caller":"traceutil/trace.go:171","msg":"trace[537655935] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"141.961085ms","start":"2026-02-12T03:34:52.78206Z","end":"2026-02-12T03:34:52.924021Z","steps":["trace[537655935] 'process raft request' (duration: 140.78912ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:52.926309Z","caller":"traceutil/trace.go:171","msg":"trace[288091131] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:309; }","duration":"218.485736ms","start":"2026-02-12T03:34:52.7078Z","end":"2026-02-12T03:34:52.926286Z","steps":["trace[288091131] 'read index received' (duration: 101.270816ms)","trace[288091131] 'applied index is now lower than readState.Index' (duration: 117.21287ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:34:52.928132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.321715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:34:52.928165Z","caller":"traceutil/trace.go:171","msg":"trace[2083877213] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:306; }","duration":"220.370966ms","start":"2026-02-12T03:34:52.707784Z","end":"2026-02-12T03:34:52.928155Z","steps":["trace[2083877213] 'agreement among raft nodes before linearized reading' (duration: 218.555828ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:53.754456Z","caller":"traceutil/trace.go:171","msg":"trace[1464356863] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"194.043971ms","start":"2026-02-12T03:34:53.560394Z","end":"2026-02-12T03:34:53.754438Z","steps":["trace[1464356863] 'process raft request' (duration: 105.465014ms)","trace[1464356863] 'compare' (duration: 88.527647ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:53.755891Z","caller":"traceutil/trace.go:171","msg":"trace[1804438760] linearizableReadLoop","detail":"{readStateIndex:332; appliedIndex:329; }","duration":"152.908051ms","start":"2026-02-12T03:34:53.602955Z","end":"2026-02-12T03:34:53.755863Z","steps":["trace[1804438760] 'read index received' (duration: 63.107489ms)","trace[1804438760] 'applied index is now lower than readState.Index' (duration: 89.799322ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:53.756027Z","caller":"traceutil/trace.go:171","msg":"trace[946209682] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"192.436263ms","start":"2026-02-12T03:34:53.563582Z","end":"2026-02-12T03:34:53.756018Z","steps":["trace[946209682] 'process raft request' (duration: 192.097851ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:53.756392Z","caller":"traceutil/trace.go:171","msg":"trace[258282856] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"178.836161ms","start":"2026-02-12T03:34:53.577544Z","end":"2026-02-12T03:34:53.75638Z","steps":["trace[258282856] 'process raft request' (duration: 178.227519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:53.75657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.589424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-12T03:34:53.756615Z","caller":"traceutil/trace.go:171","msg":"trace[727182703] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:320; }","duration":"153.722885ms","start":"2026-02-12T03:34:53.602881Z","end":"2026-02-12T03:34:53.756604Z","steps":["trace[727182703] 'agreement among raft nodes before linearized reading' (duration: 153.628935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:54.194502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.152433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:34:54.194573Z","caller":"traceutil/trace.go:171","msg":"trace[1287369611] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"394.335846ms","start":"2026-02-12T03:34:53.800225Z","end":"2026-02-12T03:34:54.194561Z","steps":["trace[1287369611] 'read index received' (duration: 192.029701ms)","trace[1287369611] 'applied index is now lower than readState.Index' (duration: 202.305385ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:54.194628Z","caller":"traceutil/trace.go:171","msg":"trace[1662698015] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"419.774453ms","start":"2026-02-12T03:34:53.774847Z","end":"2026-02-12T03:34:54.194621Z","steps":["trace[1662698015] 'process raft request' (duration: 217.449589ms)","trace[1662698015] 'compare' (duration: 202.001683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:34:54.19466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:53.774826Z","time spent":"419.817333ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:34:54.19475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.525326ms","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-12T03:34:54.194764Z","caller":"traceutil/trace.go:171","msg":"trace[108620640] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:321; }","duration":"394.539796ms","start":"2026-02-12T03:34:53.800219Z","end":"2026-02-12T03:34:54.194759Z","steps":["trace[108620640] 'agreement among raft nodes before linearized reading' (duration: 394.506346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:54.194778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:53.800156Z","time spent":"394.619098ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-12T03:34:54.194954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.658747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-12T03:34:54.194967Z","caller":"traceutil/trace.go:171","msg":"trace[538343925] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:321; }","duration":"344.758928ms","start":"2026-02-12T03:34:53.850203Z","end":"2026-02-12T03:34:54.194962Z","steps":["trace[538343925] 'agreement among raft nodes before linearized reading' (duration: 344.733668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:54.194982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:53.850175Z","time spent":"344.804328ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-02-12T03:34:54.735973Z","caller":"traceutil/trace.go:171","msg":"trace[220992788] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"252.171622ms","start":"2026-02-12T03:34:54.48378Z","end":"2026-02-12T03:34:54.735952Z","steps":["trace[220992788] 'process raft request' (duration: 218.239147ms)","trace[220992788] 'compare' (duration: 33.834595ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:54.753545Z","caller":"traceutil/trace.go:171","msg":"trace[609506755] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:339; }","duration":"210.989233ms","start":"2026-02-12T03:34:54.542538Z","end":"2026-02-12T03:34:54.753527Z","steps":["trace[609506755] 'read index received' (duration: 159.419108ms)","trace[609506755] 'applied index is now lower than readState.Index' (duration: 51.569485ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:54.753645Z","caller":"traceutil/trace.go:171","msg":"trace[937911373] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"261.723186ms","start":"2026-02-12T03:34:54.491916Z","end":"2026-02-12T03:34:54.753639Z","steps":["trace[937911373] 'process raft request' (duration: 261.502205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:54.753943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.334445ms","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-12T03:34:54.754049Z","caller":"traceutil/trace.go:171","msg":"trace[292510175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:329; }","duration":"211.465465ms","start":"2026-02-12T03:34:54.542559Z","end":"2026-02-12T03:34:54.754024Z","steps":["trace[292510175] 'agreement among raft nodes before linearized reading' (duration: 211.283674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:54.754064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.508985ms","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-12T03:34:54.754094Z","caller":"traceutil/trace.go:171","msg":"trace[1435422402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:329; }","duration":"211.577886ms","start":"2026-02-12T03:34:54.542508Z","end":"2026-02-12T03:34:54.754085Z","steps":["trace[1435422402] 'agreement among raft nodes before linearized reading' (duration: 211.518226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:54.754271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.173405ms","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-12T03:34:54.754287Z","caller":"traceutil/trace.go:171","msg":"trace[479677944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:329; }","duration":"104.192165ms","start":"2026-02-12T03:34:54.650089Z","end":"2026-02-12T03:34:54.754282Z","steps":["trace[479677944] 'agreement among raft nodes before linearized reading' (duration: 104.156685ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:54.973558Z","caller":"traceutil/trace.go:171","msg":"trace[1289141681] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"199.659443ms","start":"2026-02-12T03:34:54.773865Z","end":"2026-02-12T03:34:54.973525Z","steps":["trace[1289141681] 'process raft request' (duration: 159.767121ms)","trace[1289141681] 'compare' (duration: 39.497961ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:54.973655Z","caller":"traceutil/trace.go:171","msg":"trace[29602178] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"196.625449ms","start":"2026-02-12T03:34:54.776977Z","end":"2026-02-12T03:34:54.973602Z","steps":["trace[29602178] 'process raft request' (duration: 196.494428ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:54.973771Z","caller":"traceutil/trace.go:171","msg":"trace[1991287837] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"199.824523ms","start":"2026-02-12T03:34:54.77394Z","end":"2026-02-12T03:34:54.973764Z","steps":["trace[1991287837] 'process raft request' (duration: 199.383241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:55.192574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.873354ms","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-12T03:34:55.192815Z","caller":"traceutil/trace.go:171","msg":"trace[160687274] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"111.065976ms","start":"2026-02-12T03:34:55.081644Z","end":"2026-02-12T03:34:55.19271Z","steps":["trace[160687274] 'range keys from in-memory index tree' (duration: 110.805454ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:55.309608Z","caller":"traceutil/trace.go:171","msg":"trace[211028478] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"111.568828ms","start":"2026-02-12T03:34:55.198013Z","end":"2026-02-12T03:34:55.309582Z","steps":["trace[211028478] 'process raft request' (duration: 110.823524ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.075527Z","caller":"traceutil/trace.go:171","msg":"trace[152869142] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"242.736584ms","start":"2026-02-12T03:34:55.832763Z","end":"2026-02-12T03:34:56.075499Z","steps":["trace[152869142] 'read index received' (duration: 151.083118ms)","trace[152869142] 'applied index is now lower than readState.Index' (duration: 91.652136ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:56.07559Z","caller":"traceutil/trace.go:171","msg":"trace[1834949755] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"242.983745ms","start":"2026-02-12T03:34:55.832582Z","end":"2026-02-12T03:34:56.075566Z","steps":["trace[1834949755] 'process raft request' (duration: 151.278599ms)","trace[1834949755] 'compare' (duration: 91.488635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:34:56.075973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.494267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-l26x6\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-12T03:34:56.076081Z","caller":"traceutil/trace.go:171","msg":"trace[386167580] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-l26x6; range_end:; response_count:1; response_revision:349; }","duration":"243.645817ms","start":"2026-02-12T03:34:55.832421Z","end":"2026-02-12T03:34:56.076067Z","steps":["trace[386167580] 'agreement among raft nodes before linearized reading' (duration: 243.248586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.076609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.816752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-12T03:34:56.078482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.111437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-12T03:34:56.085766Z","caller":"traceutil/trace.go:171","msg":"trace[43772939] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:349; }","duration":"134.491321ms","start":"2026-02-12T03:34:55.951264Z","end":"2026-02-12T03:34:56.085756Z","steps":["trace[43772939] 'agreement among raft nodes before linearized reading' (duration: 127.136467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.079161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.872077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-12T03:34:56.085917Z","caller":"traceutil/trace.go:171","msg":"trace[2135299000] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:349; }","duration":"186.727938ms","start":"2026-02-12T03:34:55.899182Z","end":"2026-02-12T03:34:56.08591Z","steps":["trace[2135299000] 'agreement among raft nodes before linearized reading' (duration: 179.898467ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.085713Z","caller":"traceutil/trace.go:171","msg":"trace[1409181186] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:349; }","duration":"235.942522ms","start":"2026-02-12T03:34:55.849749Z","end":"2026-02-12T03:34:56.085692Z","steps":["trace[1409181186] 'agreement among raft nodes before linearized reading' (duration: 226.805751ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.253605Z","caller":"traceutil/trace.go:171","msg":"trace[2103937048] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"160.523549ms","start":"2026-02-12T03:34:56.093057Z","end":"2026-02-12T03:34:56.253581Z","steps":["trace[2103937048] 'process raft request' (duration: 158.100538ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.322925Z","caller":"traceutil/trace.go:171","msg":"trace[134122746] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:361; }","duration":"216.683002ms","start":"2026-02-12T03:34:56.10622Z","end":"2026-02-12T03:34:56.322903Z","steps":["trace[134122746] 'read index received' (duration: 145.099018ms)","trace[134122746] 'applied index is now lower than readState.Index' (duration: 71.582794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:34:56.323743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.500617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-12T03:34:56.324385Z","caller":"traceutil/trace.go:171","msg":"trace[1609354687] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:352; }","duration":"218.183079ms","start":"2026-02-12T03:34:56.106193Z","end":"2026-02-12T03:34:56.324376Z","steps":["trace[1609354687] 'agreement among raft nodes before linearized reading' (duration: 217.078725ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.323996Z","caller":"traceutil/trace.go:171","msg":"trace[113778133] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"224.500987ms","start":"2026-02-12T03:34:56.099439Z","end":"2026-02-12T03:34:56.32394Z","steps":["trace[113778133] 'process raft request' (duration: 223.157231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.324245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.25587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-12T03:34:56.32484Z","caller":"traceutil/trace.go:171","msg":"trace[755675950] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:352; }","duration":"174.818372ms","start":"2026-02-12T03:34:56.149937Z","end":"2026-02-12T03:34:56.324756Z","steps":["trace[755675950] 'agreement among raft nodes before linearized reading' (duration: 174.172639ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.324318Z","caller":"traceutil/trace.go:171","msg":"trace[161363005] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"218.25475ms","start":"2026-02-12T03:34:56.106054Z","end":"2026-02-12T03:34:56.324308Z","steps":["trace[161363005] 'process raft request' (duration: 216.782013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.325345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.838035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-12T03:34:56.325389Z","caller":"traceutil/trace.go:171","msg":"trace[1629864421] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:352; }","duration":"124.934125ms","start":"2026-02-12T03:34:56.200441Z","end":"2026-02-12T03:34:56.325375Z","steps":["trace[1629864421] 'agreement among raft nodes before linearized reading' (duration: 124.835305ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.471342Z","caller":"traceutil/trace.go:171","msg":"trace[150712083] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"132.782372ms","start":"2026-02-12T03:34:56.338468Z","end":"2026-02-12T03:34:56.47125Z","steps":["trace[150712083] 'process raft request' (duration: 132.531891ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.515758Z","caller":"traceutil/trace.go:171","msg":"trace[1937863875] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:365; }","duration":"166.677546ms","start":"2026-02-12T03:34:56.34905Z","end":"2026-02-12T03:34:56.515727Z","steps":["trace[1937863875] 'read index received' (duration: 125.031397ms)","trace[1937863875] 'applied index is now lower than readState.Index' (duration: 41.644769ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:56.51589Z","caller":"traceutil/trace.go:171","msg":"trace[1464741064] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"177.101884ms","start":"2026-02-12T03:34:56.338777Z","end":"2026-02-12T03:34:56.515879Z","steps":["trace[1464741064] 'process raft request' (duration: 176.560271ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.516224Z","caller":"traceutil/trace.go:171","msg":"trace[740949721] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"172.615853ms","start":"2026-02-12T03:34:56.343532Z","end":"2026-02-12T03:34:56.516148Z","steps":["trace[740949721] 'process raft request' (duration: 172.078671ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.516589Z","caller":"traceutil/trace.go:171","msg":"trace[1956431970] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"172.928975ms","start":"2026-02-12T03:34:56.343645Z","end":"2026-02-12T03:34:56.516574Z","steps":["trace[1956431970] 'process raft request' (duration: 172.03395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.51688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.767242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-12T03:34:56.516931Z","caller":"traceutil/trace.go:171","msg":"trace[1351773532] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:358; }","duration":"167.890202ms","start":"2026-02-12T03:34:56.349028Z","end":"2026-02-12T03:34:56.516918Z","steps":["trace[1351773532] 'agreement among raft nodes before linearized reading' (duration: 167.742221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.51724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.610589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-12T03:34:56.517294Z","caller":"traceutil/trace.go:171","msg":"trace[1226450757] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:358; }","duration":"116.729569ms","start":"2026-02-12T03:34:56.400553Z","end":"2026-02-12T03:34:56.517282Z","steps":["trace[1226450757] 'agreement among raft nodes before linearized reading' (duration: 116.591839ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.519163Z","caller":"traceutil/trace.go:171","msg":"trace[1384604596] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"175.967018ms","start":"2026-02-12T03:34:56.343181Z","end":"2026-02-12T03:34:56.519148Z","steps":["trace[1384604596] 'process raft request' (duration: 172.299991ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:56.519781Z","caller":"traceutil/trace.go:171","msg":"trace[68262190] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"176.441861ms","start":"2026-02-12T03:34:56.343324Z","end":"2026-02-12T03:34:56.519766Z","steps":["trace[68262190] 'process raft request' (duration: 172.215261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.9636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.91946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:34:56.963731Z","caller":"traceutil/trace.go:171","msg":"trace[332669555] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"425.179838ms","start":"2026-02-12T03:34:56.538528Z","end":"2026-02-12T03:34:56.963708Z","steps":["trace[332669555] 'process raft request' (duration: 273.054018ms)","trace[332669555] 'compare' (duration: 151.744779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:34:56.964075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.538515Z","time spent":"425.52412ms","remote":"127.0.0.1:51898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:34:56.964679Z","caller":"traceutil/trace.go:171","msg":"trace[1213844091] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:370; }","duration":"415.519585ms","start":"2026-02-12T03:34:56.549147Z","end":"2026-02-12T03:34:56.964667Z","steps":["trace[1213844091] 'read index received' (duration: 262.3364ms)","trace[1213844091] 'applied index is now lower than readState.Index' (duration: 153.182355ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:34:56.964773Z","caller":"traceutil/trace.go:171","msg":"trace[539456490] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"422.277035ms","start":"2026-02-12T03:34:56.542487Z","end":"2026-02-12T03:34:56.964764Z","steps":["trace[539456490] 'process raft request' (duration: 421.840012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.964819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.542478Z","time spent":"422.314655ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:34:56.964947Z","caller":"traceutil/trace.go:171","msg":"trace[534410301] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"420.081365ms","start":"2026-02-12T03:34:56.544857Z","end":"2026-02-12T03:34:56.964939Z","steps":["trace[534410301] 'process raft request' (duration: 419.521571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.964985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.54485Z","time spent":"420.116625ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:34:56.965225Z","caller":"traceutil/trace.go:171","msg":"trace[1598555789] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"420.277976ms","start":"2026-02-12T03:34:56.544937Z","end":"2026-02-12T03:34:56.965215Z","steps":["trace[1598555789] 'process raft request' (duration: 419.472881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.966215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.544932Z","time spent":"421.22862ms","remote":"127.0.0.1:51988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:34:56.966493Z","caller":"traceutil/trace.go:171","msg":"trace[1385582115] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"420.510747ms","start":"2026-02-12T03:34:56.545969Z","end":"2026-02-12T03:34:56.96648Z","steps":["trace[1385582115] 'process raft request' (duration: 418.480278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.966545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.545949Z","time spent":"420.570877ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:34:56.966828Z","caller":"traceutil/trace.go:171","msg":"trace[1558748811] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"420.807968ms","start":"2026-02-12T03:34:56.546008Z","end":"2026-02-12T03:34:56.966816Z","steps":["trace[1558748811] 'process raft request' (duration: 418.478028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.966878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.546004Z","time spent":"420.847099ms","remote":"127.0.0.1:51988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:34:56.96717Z","caller":"traceutil/trace.go:171","msg":"trace[449137641] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"421.091579ms","start":"2026-02-12T03:34:56.546061Z","end":"2026-02-12T03:34:56.967153Z","steps":["trace[449137641] 'process raft request' (duration: 418.474768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.967248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.546056Z","time spent":"421.150689ms","remote":"127.0.0.1:51812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3999,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:34:56.96755Z","caller":"traceutil/trace.go:171","msg":"trace[461635317] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"420.598818ms","start":"2026-02-12T03:34:56.546937Z","end":"2026-02-12T03:34:56.967536Z","steps":["trace[461635317] 'process raft request' (duration: 417.638695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.967626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.546931Z","time spent":"420.659489ms","remote":"127.0.0.1:51740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:34:56.967832Z","caller":"traceutil/trace.go:171","msg":"trace[1350868540] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"419.080461ms","start":"2026-02-12T03:34:56.548733Z","end":"2026-02-12T03:34:56.967814Z","steps":["trace[1350868540] 'process raft request' (duration: 415.871506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.967912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.548726Z","time spent":"419.140871ms","remote":"127.0.0.1:51988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:34:56.96813Z","caller":"traceutil/trace.go:171","msg":"trace[301311780] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"418.9046ms","start":"2026-02-12T03:34:56.549214Z","end":"2026-02-12T03:34:56.968119Z","steps":["trace[301311780] 'process raft request' (duration: 415.416704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.968188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.549208Z","time spent":"418.94828ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:34:56.968389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.200962ms","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-12T03:34:56.968474Z","caller":"traceutil/trace.go:171","msg":"trace[976161591] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:368; }","duration":"419.328032ms","start":"2026-02-12T03:34:56.549126Z","end":"2026-02-12T03:34:56.968454Z","steps":["trace[976161591] 'agreement among raft nodes before linearized reading' (duration: 419.127951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.968519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.549119Z","time spent":"419.393553ms","remote":"127.0.0.1:51830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-12T03:34:56.968703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.700428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-12T03:34:56.968724Z","caller":"traceutil/trace.go:171","msg":"trace[861630703] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:368; }","duration":"365.762989ms","start":"2026-02-12T03:34:56.602955Z","end":"2026-02-12T03:34:56.968718Z","steps":["trace[861630703] 'agreement among raft nodes before linearized reading' (duration: 365.706289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.968746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.602934Z","time spent":"365.807459ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-12T03:34:56.969004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.026583ms","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-12T03:34:56.969044Z","caller":"traceutil/trace.go:171","msg":"trace[1666141897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"157.094473ms","start":"2026-02-12T03:34:56.811938Z","end":"2026-02-12T03:34:56.969032Z","steps":["trace[1666141897] 'agreement among raft nodes before linearized reading' (duration: 157.024592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.96906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.843535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-l26x6.1893626c5fef659e\" ","response":"range_response_count:1 size:769"} {"level":"info","ts":"2026-02-12T03:34:56.969087Z","caller":"traceutil/trace.go:171","msg":"trace[1269735721] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-l26x6.1893626c5fef659e; range_end:; response_count:1; response_revision:368; }","duration":"309.885385ms","start":"2026-02-12T03:34:56.659192Z","end":"2026-02-12T03:34:56.969077Z","steps":["trace[1269735721] 'agreement among raft nodes before linearized reading' (duration: 309.837995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:56.969109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:34:56.659176Z","time spent":"309.928406ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":792,"request content":"key:\"/registry/events/kube-system/kube-proxy-l26x6.1893626c5fef659e\" "} {"level":"warn","ts":"2026-02-12T03:34:56.969011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.81234ms","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-12T03:34:56.969253Z","caller":"traceutil/trace.go:171","msg":"trace[1929702309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"192.074751ms","start":"2026-02-12T03:34:56.777164Z","end":"2026-02-12T03:34:56.969239Z","steps":["trace[1929702309] 'agreement among raft nodes before linearized reading' (duration: 191.806399ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.095291Z","caller":"traceutil/trace.go:171","msg":"trace[1324764924] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"114.551078ms","start":"2026-02-12T03:34:56.980702Z","end":"2026-02-12T03:34:57.095253Z","steps":["trace[1324764924] 'process raft request' (duration: 113.056952ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.097798Z","caller":"traceutil/trace.go:171","msg":"trace[2065900837] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"116.956508ms","start":"2026-02-12T03:34:56.980827Z","end":"2026-02-12T03:34:57.097784Z","steps":["trace[2065900837] 'process raft request' (duration: 114.354697ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.099074Z","caller":"traceutil/trace.go:171","msg":"trace[520738297] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"117.018339ms","start":"2026-02-12T03:34:56.982017Z","end":"2026-02-12T03:34:57.099035Z","steps":["trace[520738297] 'process raft request' (duration: 115.541622ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.102207Z","caller":"traceutil/trace.go:171","msg":"trace[738082735] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"116.128014ms","start":"2026-02-12T03:34:56.986061Z","end":"2026-02-12T03:34:57.102189Z","steps":["trace[738082735] 'process raft request' (duration: 115.136039ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.104429Z","caller":"traceutil/trace.go:171","msg":"trace[158672610] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"117.411859ms","start":"2026-02-12T03:34:56.987005Z","end":"2026-02-12T03:34:57.104416Z","steps":["trace[158672610] 'process raft request' (duration: 114.316375ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.106207Z","caller":"traceutil/trace.go:171","msg":"trace[1721744741] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"117.043348ms","start":"2026-02-12T03:34:56.98913Z","end":"2026-02-12T03:34:57.106173Z","steps":["trace[1721744741] 'process raft request' (duration: 112.402497ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.10648Z","caller":"traceutil/trace.go:171","msg":"trace[438976990] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:381; }","duration":"115.010399ms","start":"2026-02-12T03:34:56.991458Z","end":"2026-02-12T03:34:57.106468Z","steps":["trace[438976990] 'read index received' (duration: 102.313053ms)","trace[438976990] 'applied index is now lower than readState.Index' (duration: 12.696296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:34:57.106573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.605705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:2205"} {"level":"info","ts":"2026-02-12T03:34:57.107354Z","caller":"traceutil/trace.go:171","msg":"trace[157920081] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:376; }","duration":"121.412568ms","start":"2026-02-12T03:34:56.985922Z","end":"2026-02-12T03:34:57.107335Z","steps":["trace[157920081] 'agreement among raft nodes before linearized reading' (duration: 120.581964ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.10673Z","caller":"traceutil/trace.go:171","msg":"trace[393720516] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"116.742246ms","start":"2026-02-12T03:34:56.989978Z","end":"2026-02-12T03:34:57.106721Z","steps":["trace[393720516] 'process raft request' (duration: 114.407236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:57.106779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.459235ms","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-12T03:34:57.107659Z","caller":"traceutil/trace.go:171","msg":"trace[319357404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"117.39434ms","start":"2026-02-12T03:34:56.990259Z","end":"2026-02-12T03:34:57.107654Z","steps":["trace[319357404] 'agreement among raft nodes before linearized reading' (duration: 116.490675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:34:57.106817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.722196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-02-12T03:34:57.107799Z","caller":"traceutil/trace.go:171","msg":"trace[1831396536] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:376; }","duration":"119.717841ms","start":"2026-02-12T03:34:56.988076Z","end":"2026-02-12T03:34:57.107794Z","steps":["trace[1831396536] 'agreement among raft nodes before linearized reading' (duration: 118.718366ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:34:57.122362Z","caller":"traceutil/trace.go:171","msg":"trace[1291717768] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"125.506566ms","start":"2026-02-12T03:34:56.996841Z","end":"2026-02-12T03:34:57.122348Z","steps":["trace[1291717768] 'process raft request' (duration: 125.426556ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:00.778609Z","caller":"traceutil/trace.go:171","msg":"trace[1701470691] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"178.057515ms","start":"2026-02-12T03:35:00.600535Z","end":"2026-02-12T03:35:00.778593Z","steps":["trace[1701470691] 'process raft request' (duration: 177.914145ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:01.489232Z","caller":"traceutil/trace.go:171","msg":"trace[491903692] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"234.735756ms","start":"2026-02-12T03:35:01.254445Z","end":"2026-02-12T03:35:01.489181Z","steps":["trace[491903692] 'process raft request' (duration: 169.742895ms)","trace[491903692] 'compare' (duration: 64.338077ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:01.489759Z","caller":"traceutil/trace.go:171","msg":"trace[2048615816] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"137.297531ms","start":"2026-02-12T03:35:01.352448Z","end":"2026-02-12T03:35:01.489745Z","steps":["trace[2048615816] 'read index received' (duration: 71.676998ms)","trace[2048615816] 'applied index is now lower than readState.Index' (duration: 65.618493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:01.49Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.531322ms","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-12T03:35:01.490032Z","caller":"traceutil/trace.go:171","msg":"trace[1184306372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"137.635093ms","start":"2026-02-12T03:35:01.352387Z","end":"2026-02-12T03:35:01.490022Z","steps":["trace[1184306372] 'agreement among raft nodes before linearized reading' (duration: 137.396742ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:07.71393Z","caller":"traceutil/trace.go:171","msg":"trace[789123778] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"431.269256ms","start":"2026-02-12T03:35:07.282643Z","end":"2026-02-12T03:35:07.713912Z","steps":["trace[789123778] 'read index received' (duration: 429.97215ms)","trace[789123778] 'applied index is now lower than readState.Index' (duration: 1.296356ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:07.714009Z","caller":"traceutil/trace.go:171","msg":"trace[1995341786] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"502.409518ms","start":"2026-02-12T03:35:07.211592Z","end":"2026-02-12T03:35:07.714002Z","steps":["trace[1995341786] 'process raft request' (duration: 501.071841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:07.714278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:07.211572Z","time spent":"502.454669ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:35:07.71447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.35922ms","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-12T03:35:07.714578Z","caller":"traceutil/trace.go:171","msg":"trace[2026322185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:429; }","duration":"409.527971ms","start":"2026-02-12T03:35:07.305032Z","end":"2026-02-12T03:35:07.71456Z","steps":["trace[2026322185] 'agreement among raft nodes before linearized reading' (duration: 409.34086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:07.714676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:07.304996Z","time spent":"409.667811ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:35:07.714878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.960295ms","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-12T03:35:07.714934Z","caller":"traceutil/trace.go:171","msg":"trace[2052979720] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"168.022775ms","start":"2026-02-12T03:35:07.546884Z","end":"2026-02-12T03:35:07.714906Z","steps":["trace[2052979720] 'agreement among raft nodes before linearized reading' (duration: 167.958185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:07.715102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.25637ms","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-12T03:35:07.715185Z","caller":"traceutil/trace.go:171","msg":"trace[2121253048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"169.37369ms","start":"2026-02-12T03:35:07.5458Z","end":"2026-02-12T03:35:07.715174Z","steps":["trace[2121253048] 'agreement among raft nodes before linearized reading' (duration: 169.23116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:07.715298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.652262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:07.715328Z","caller":"traceutil/trace.go:171","msg":"trace[2090810747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"432.717392ms","start":"2026-02-12T03:35:07.282603Z","end":"2026-02-12T03:35:07.715321Z","steps":["trace[2090810747] 'agreement among raft nodes before linearized reading' (duration: 432.642552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:07.715434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:07.282562Z","time spent":"432.781872ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:35:08.302162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740062967732648,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:08.802544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740062967732648,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:09.302914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740062967732648,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:09.476199Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.750794302s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T03:35:09.806388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.005269938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-12T03:35:09.806468Z","caller":"traceutil/trace.go:171","msg":"trace[178126140] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.005379158s","start":"2026-02-12T03:35:07.801074Z","end":"2026-02-12T03:35:09.806454Z","steps":["trace[178126140] 'agreement among raft nodes before linearized reading' (duration: 2.005289808s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:09.806585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:07.801065Z","time spent":"2.005511539s","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/12 03:35:09 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-12T03:35:10.317037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.679248ms","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-12T03:35:10.317167Z","caller":"traceutil/trace.go:171","msg":"trace[107212140] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:429; }","duration":"542.835049ms","start":"2026-02-12T03:35:09.774314Z","end":"2026-02-12T03:35:10.317149Z","steps":["trace[107212140] 'range keys from in-memory index tree' (duration: 542.662428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.318875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.456614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:10.318919Z","caller":"traceutil/trace.go:171","msg":"trace[1534853777] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"2.593556666s","start":"2026-02-12T03:35:07.725354Z","end":"2026-02-12T03:35:10.318911Z","steps":["trace[1534853777] 'process raft request' (duration: 1.751000082s)","trace[1534853777] 'compare' (duration: 842.044431ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:10.318957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:07.725339Z","time spent":"2.593599906s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:10.397192Z","caller":"traceutil/trace.go:171","msg":"trace[598190407] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:445; }","duration":"2.596076605s","start":"2026-02-12T03:35:07.8011Z","end":"2026-02-12T03:35:10.397177Z","steps":["trace[598190407] 'read index received' (duration: 1.675257531s)","trace[598190407] 'applied index is now lower than readState.Index' (duration: 920.818244ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:10.397432Z","caller":"traceutil/trace.go:171","msg":"trace[2036860079] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"1.6252481s","start":"2026-02-12T03:35:08.772146Z","end":"2026-02-12T03:35:10.397394Z","steps":["trace[2036860079] 'process raft request' (duration: 1.624948398s)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:10.397182Z","caller":"traceutil/trace.go:171","msg":"trace[1751431086] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"2.670029239s","start":"2026-02-12T03:35:07.727122Z","end":"2026-02-12T03:35:10.397151Z","steps":["trace[1751431086] 'process raft request' (duration: 2.669861918s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.398504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:08.772124Z","time spent":"1.62535927s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:35:10.398864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:07.727114Z","time spent":"2.670791332s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:10.397262Z","caller":"traceutil/trace.go:171","msg":"trace[587941121] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"2.671344504s","start":"2026-02-12T03:35:07.725908Z","end":"2026-02-12T03:35:10.397252Z","steps":["trace[587941121] 'process raft request' (duration: 2.670868251s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.399446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:07.725887Z","time spent":"2.673479892s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:35:10.407461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369241423s","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-12T03:35:10.407604Z","caller":"traceutil/trace.go:171","msg":"trace[1175385471] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"588.872557ms","start":"2026-02-12T03:35:09.818714Z","end":"2026-02-12T03:35:10.407587Z","steps":["trace[1175385471] 'process raft request' (duration: 588.587156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.407723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:09.818682Z","time spent":"589.002587ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:35:10.407633Z","caller":"traceutil/trace.go:171","msg":"trace[780660995] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:434; }","duration":"1.369442244s","start":"2026-02-12T03:35:09.038179Z","end":"2026-02-12T03:35:10.407621Z","steps":["trace[780660995] 'agreement among raft nodes before linearized reading' (duration: 1.369195723s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.407915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:09.038163Z","time spent":"1.369741185s","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4057,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-12T03:35:10.408191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.419886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-12T03:35:10.40752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.677465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:10.408405Z","caller":"traceutil/trace.go:171","msg":"trace[1143889856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"543.61634ms","start":"2026-02-12T03:35:09.864777Z","end":"2026-02-12T03:35:10.408394Z","steps":["trace[1143889856] 'agreement among raft nodes before linearized reading' (duration: 542.608055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.408493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:09.864761Z","time spent":"543.72243ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:35:10.40882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.485798ms","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-12T03:35:10.40885Z","caller":"traceutil/trace.go:171","msg":"trace[985026889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"685.545488ms","start":"2026-02-12T03:35:09.723298Z","end":"2026-02-12T03:35:10.408843Z","steps":["trace[985026889] 'agreement among raft nodes before linearized reading' (duration: 685.477608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.40894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:09.723282Z","time spent":"685.643058ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T03:35:10.40829Z","caller":"traceutil/trace.go:171","msg":"trace[781773721] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:434; }","duration":"655.503087ms","start":"2026-02-12T03:35:09.752746Z","end":"2026-02-12T03:35:10.408249Z","steps":["trace[781773721] 'agreement among raft nodes before linearized reading' (duration: 655.408266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:10.412555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:09.752738Z","time spent":"659.778355ms","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"info","ts":"2026-02-12T03:35:12.528256Z","caller":"traceutil/trace.go:171","msg":"trace[1861374225] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"122.116347ms","start":"2026-02-12T03:35:12.406123Z","end":"2026-02-12T03:35:12.528239Z","steps":["trace[1861374225] 'read index received' (duration: 121.967846ms)","trace[1861374225] 'applied index is now lower than readState.Index' (duration: 147.961µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:12.528484Z","caller":"traceutil/trace.go:171","msg":"trace[22858885] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"122.785319ms","start":"2026-02-12T03:35:12.405693Z","end":"2026-02-12T03:35:12.528478Z","steps":["trace[22858885] 'process raft request' (duration: 122.438728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:12.528609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.473798ms","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-12T03:35:12.528628Z","caller":"traceutil/trace.go:171","msg":"trace[608718945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"122.517079ms","start":"2026-02-12T03:35:12.406105Z","end":"2026-02-12T03:35:12.528622Z","steps":["trace[608718945] 'agreement among raft nodes before linearized reading' (duration: 122.470948ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:14.852878Z","caller":"traceutil/trace.go:171","msg":"trace[555694794] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"312.323821ms","start":"2026-02-12T03:35:14.540527Z","end":"2026-02-12T03:35:14.852851Z","steps":["trace[555694794] 'process raft request' (duration: 312.179771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:14.853034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:14.540513Z","time spent":"312.434442ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:14.863297Z","caller":"traceutil/trace.go:171","msg":"trace[826994577] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"313.194987ms","start":"2026-02-12T03:35:14.550087Z","end":"2026-02-12T03:35:14.863282Z","steps":["trace[826994577] 'read index received' (duration: 302.651961ms)","trace[826994577] 'applied index is now lower than readState.Index' (duration: 10.541786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:14.86338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.282777ms","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-12T03:35:14.863407Z","caller":"traceutil/trace.go:171","msg":"trace[195890516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:473; }","duration":"313.335537ms","start":"2026-02-12T03:35:14.550062Z","end":"2026-02-12T03:35:14.863398Z","steps":["trace[195890516] 'agreement among raft nodes before linearized reading' (duration: 313.273567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:14.863434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:14.550054Z","time spent":"313.372657ms","remote":"127.0.0.1:51902","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-12T03:35:14.863523Z","caller":"traceutil/trace.go:171","msg":"trace[843863144] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"313.468577ms","start":"2026-02-12T03:35:14.550043Z","end":"2026-02-12T03:35:14.863512Z","steps":["trace[843863144] 'process raft request' (duration: 313.152046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:14.863578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:14.55001Z","time spent":"313.527328ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:18.542403Z","caller":"traceutil/trace.go:171","msg":"trace[1577332436] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"138.459879ms","start":"2026-02-12T03:35:18.403923Z","end":"2026-02-12T03:35:18.542383Z","steps":["trace[1577332436] 'process raft request' (duration: 138.298059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:25.48302Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740062967732838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:25.936886Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.048637931s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T03:35:25.937211Z","caller":"traceutil/trace.go:171","msg":"trace[801524458] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.049040323s","start":"2026-02-12T03:35:24.888146Z","end":"2026-02-12T03:35:25.937187Z","steps":["trace[801524458] 'process raft request' (duration: 1.048904422s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:25.93738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:24.888136Z","time spent":"1.049166013s","remote":"127.0.0.1:51812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:25.937901Z","caller":"traceutil/trace.go:171","msg":"trace[1046020152] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:549; }","duration":"955.442669ms","start":"2026-02-12T03:35:24.982447Z","end":"2026-02-12T03:35:25.93789Z","steps":["trace[1046020152] 'read index received' (duration: 955.438619ms)","trace[1046020152] 'applied index is now lower than readState.Index' (duration: 3.36µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:25.93798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.54204ms","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-12T03:35:25.938009Z","caller":"traceutil/trace.go:171","msg":"trace[343654457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"955.63147ms","start":"2026-02-12T03:35:24.982368Z","end":"2026-02-12T03:35:25.938Z","steps":["trace[343654457] 'agreement among raft nodes before linearized reading' (duration: 955.57861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:25.938036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:24.982335Z","time spent":"955.69463ms","remote":"127.0.0.1:51902","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-12T03:35:26.503101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:26.15285Z","time spent":"350.242541ms","remote":"127.0.0.1:51292","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T03:35:26.509167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.679629ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T03:35:26.509359Z","caller":"traceutil/trace.go:171","msg":"trace[242658777] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"563.198975ms","start":"2026-02-12T03:35:25.946149Z","end":"2026-02-12T03:35:26.509348Z","steps":["trace[242658777] 'process raft request' (duration: 563.097895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:26.509441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:25.946137Z","time spent":"563.250606ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:26.509597Z","caller":"traceutil/trace.go:171","msg":"trace[1308601205] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"571.662431ms","start":"2026-02-12T03:35:25.937928Z","end":"2026-02-12T03:35:26.50959Z","steps":["trace[1308601205] 'read index received' (duration: 116.517791ms)","trace[1308601205] 'applied index is now lower than readState.Index' (duration: 455.14396ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:26.509685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480581674s","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-12T03:35:26.509702Z","caller":"traceutil/trace.go:171","msg":"trace[1605018098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"1.480653954s","start":"2026-02-12T03:35:25.029044Z","end":"2026-02-12T03:35:26.509698Z","steps":["trace[1605018098] 'agreement among raft nodes before linearized reading' (duration: 1.480572853s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:26.509717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:25.029023Z","time spent":"1.480691104s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T03:35:26.509827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.149999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:26.509845Z","caller":"traceutil/trace.go:171","msg":"trace[830674790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"709.211979ms","start":"2026-02-12T03:35:25.800629Z","end":"2026-02-12T03:35:26.509841Z","steps":["trace[830674790] 'agreement among raft nodes before linearized reading' (duration: 709.187579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:26.509856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:25.800608Z","time spent":"709.245279ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:35:26.509942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416496094s","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-12T03:35:26.509961Z","caller":"traceutil/trace.go:171","msg":"trace[1257308449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"1.416548514s","start":"2026-02-12T03:35:25.093407Z","end":"2026-02-12T03:35:26.509955Z","steps":["trace[1257308449] 'agreement among raft nodes before linearized reading' (duration: 1.416507234s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:26.509974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:25.093386Z","time spent":"1.416584884s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T03:35:33.921213Z","caller":"traceutil/trace.go:171","msg":"trace[1201403943] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"154.386152ms","start":"2026-02-12T03:35:33.76681Z","end":"2026-02-12T03:35:33.921196Z","steps":["trace[1201403943] 'process raft request' (duration: 154.286411ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:34.140256Z","caller":"traceutil/trace.go:171","msg":"trace[2009669262] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"302.812397ms","start":"2026-02-12T03:35:33.837426Z","end":"2026-02-12T03:35:34.140239Z","steps":["trace[2009669262] 'read index received' (duration: 84.145769ms)","trace[2009669262] 'applied index is now lower than readState.Index' (duration: 218.666058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:34.140359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.911788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:34.140377Z","caller":"traceutil/trace.go:171","msg":"trace[1230154672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"302.963769ms","start":"2026-02-12T03:35:33.837407Z","end":"2026-02-12T03:35:34.14037Z","steps":["trace[1230154672] 'agreement among raft nodes before linearized reading' (duration: 302.893009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:34.140395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:33.837399Z","time spent":"302.992289ms","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T03:35:34.140593Z","caller":"traceutil/trace.go:171","msg":"trace[1789722056] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"360.694998ms","start":"2026-02-12T03:35:33.779891Z","end":"2026-02-12T03:35:34.140586Z","steps":["trace[1789722056] 'process raft request' (duration: 360.198686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:34.14065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:33.779882Z","time spent":"360.722348ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:35:37.30185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740062967732938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:37.802027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581740062967732938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:35:37.825771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.694679163s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T03:35:37.887904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.352987053s","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-12T03:35:37.887987Z","caller":"traceutil/trace.go:171","msg":"trace[3046530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"3.353114233s","start":"2026-02-12T03:35:34.534854Z","end":"2026-02-12T03:35:37.887968Z","steps":["trace[3046530] 'range keys from in-memory index tree' (duration: 3.352842373s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.888024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.534841Z","time spent":"3.353175264s","remote":"127.0.0.1:51902","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-12T03:35:37.888225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.349420197s","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-12T03:35:37.888246Z","caller":"traceutil/trace.go:171","msg":"trace[1018194131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"3.349449517s","start":"2026-02-12T03:35:34.538788Z","end":"2026-02-12T03:35:37.888238Z","steps":["trace[1018194131] 'range keys from in-memory index tree' (duration: 3.349370127s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.888267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.538782Z","time spent":"3.349477627s","remote":"127.0.0.1:51902","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-12T03:35:37.888375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.33300734s","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-12T03:35:37.888393Z","caller":"traceutil/trace.go:171","msg":"trace[1146998856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"3.33303457s","start":"2026-02-12T03:35:34.555351Z","end":"2026-02-12T03:35:37.888386Z","steps":["trace[1146998856] 'range keys from in-memory index tree' (duration: 3.33295264s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.888411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.555345Z","time spent":"3.333061s","remote":"127.0.0.1:51902","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-12T03:35:37.888619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.276851566s","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-12T03:35:37.888638Z","caller":"traceutil/trace.go:171","msg":"trace[756617343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"3.276891586s","start":"2026-02-12T03:35:34.611739Z","end":"2026-02-12T03:35:37.888631Z","steps":["trace[756617343] 'range keys from in-memory index tree' (duration: 3.276786546s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.888659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.611728Z","time spent":"3.276925606s","remote":"127.0.0.1:51902","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-12T03:35:37.888898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.088539364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:37.888916Z","caller":"traceutil/trace.go:171","msg":"trace[1612791770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"3.088579104s","start":"2026-02-12T03:35:34.800331Z","end":"2026-02-12T03:35:37.88891Z","steps":["trace[1612791770] 'range keys from in-memory index tree' (duration: 3.088495204s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.888933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:34.800318Z","time spent":"3.088610554s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T03:35:37.924627Z","caller":"traceutil/trace.go:171","msg":"trace[36632416] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"1.123198818s","start":"2026-02-12T03:35:36.801398Z","end":"2026-02-12T03:35:37.924596Z","steps":["trace[36632416] 'read index received' (duration: 1.02455176s)","trace[36632416] 'applied index is now lower than readState.Index' (duration: 98.645568ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:37.924838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123409849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:37.92489Z","caller":"traceutil/trace.go:171","msg":"trace[1081236259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"1.123539909s","start":"2026-02-12T03:35:36.801343Z","end":"2026-02-12T03:35:37.924883Z","steps":["trace[1081236259] 'agreement among raft nodes before linearized reading' (duration: 1.123367298s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.924931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:36.801301Z","time spent":"1.123616629s","remote":"127.0.0.1:33280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:35:37.942115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.879801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:37.94219Z","caller":"traceutil/trace.go:171","msg":"trace[1380589270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"658.237943ms","start":"2026-02-12T03:35:37.283938Z","end":"2026-02-12T03:35:37.942176Z","steps":["trace[1380589270] 'agreement among raft nodes before linearized reading' (duration: 657.906992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:37.942211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:37.283913Z","time spent":"658.292183ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T03:35:39.89596Z","caller":"traceutil/trace.go:171","msg":"trace[1939949301] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"111.414671ms","start":"2026-02-12T03:35:39.784528Z","end":"2026-02-12T03:35:39.895943Z","steps":["trace[1939949301] 'process raft request' (duration: 104.758103ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:39.907999Z","caller":"traceutil/trace.go:171","msg":"trace[294003311] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"103.185236ms","start":"2026-02-12T03:35:39.804782Z","end":"2026-02-12T03:35:39.907967Z","steps":["trace[294003311] 'process raft request' (duration: 103.015635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:50.264863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.086745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:50.265083Z","caller":"traceutil/trace.go:171","msg":"trace[1674395435] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"144.814111ms","start":"2026-02-12T03:35:50.120256Z","end":"2026-02-12T03:35:50.26507Z","steps":["trace[1674395435] 'process raft request' (duration: 13.404575ms)","trace[1674395435] 'compare' (duration: 130.958464ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:50.265492Z","caller":"traceutil/trace.go:171","msg":"trace[1715816772] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"140.172082ms","start":"2026-02-12T03:35:50.125312Z","end":"2026-02-12T03:35:50.265484Z","steps":["trace[1715816772] 'process raft request' (duration: 140.103702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:50.536629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.223182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:50.536745Z","caller":"traceutil/trace.go:171","msg":"trace[1927322808] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"266.96303ms","start":"2026-02-12T03:35:50.26977Z","end":"2026-02-12T03:35:50.536733Z","steps":["trace[1927322808] 'process raft request' (duration: 158.582238ms)","trace[1927322808] 'compare' (duration: 108.067181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:50.899064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.777536ms","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-12T03:35:50.899121Z","caller":"traceutil/trace.go:171","msg":"trace[1538978423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:719; }","duration":"238.868417ms","start":"2026-02-12T03:35:50.660243Z","end":"2026-02-12T03:35:50.899111Z","steps":["trace[1538978423] 'range keys from in-memory index tree' (duration: 238.654065ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:51.050788Z","caller":"traceutil/trace.go:171","msg":"trace[473034323] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"146.813721ms","start":"2026-02-12T03:35:50.903957Z","end":"2026-02-12T03:35:51.050771Z","steps":["trace[473034323] 'read index received' (duration: 146.75889ms)","trace[473034323] 'applied index is now lower than readState.Index' (duration: 53.691µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:51.050939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.964801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-zx2cz\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-02-12T03:35:51.050961Z","caller":"traceutil/trace.go:171","msg":"trace[216781422] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-zx2cz; range_end:; response_count:1; response_revision:720; }","duration":"147.018241ms","start":"2026-02-12T03:35:50.903937Z","end":"2026-02-12T03:35:51.050955Z","steps":["trace[216781422] 'agreement among raft nodes before linearized reading' (duration: 146.894491ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:51.05106Z","caller":"traceutil/trace.go:171","msg":"trace[1593938989] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"193.374911ms","start":"2026-02-12T03:35:50.857668Z","end":"2026-02-12T03:35:51.051043Z","steps":["trace[1593938989] 'process raft request' (duration: 192.990209ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:51.092941Z","caller":"traceutil/trace.go:171","msg":"trace[754156826] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"188.853821ms","start":"2026-02-12T03:35:50.904072Z","end":"2026-02-12T03:35:51.092925Z","steps":["trace[754156826] 'process raft request' (duration: 188.7447ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:51.433463Z","caller":"traceutil/trace.go:171","msg":"trace[556256837] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"175.694178ms","start":"2026-02-12T03:35:51.257752Z","end":"2026-02-12T03:35:51.433446Z","steps":["trace[556256837] 'read index received' (duration: 175.545006ms)","trace[556256837] 'applied index is now lower than readState.Index' (duration: 148.562µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:51.433573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.800868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:35:51.433595Z","caller":"traceutil/trace.go:171","msg":"trace[743647733] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:722; }","duration":"175.868369ms","start":"2026-02-12T03:35:51.25772Z","end":"2026-02-12T03:35:51.433589Z","steps":["trace[743647733] 'agreement among raft nodes before linearized reading' (duration: 175.787708ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:51.433866Z","caller":"traceutil/trace.go:171","msg":"trace[512279200] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"376.70178ms","start":"2026-02-12T03:35:51.057157Z","end":"2026-02-12T03:35:51.433858Z","steps":["trace[512279200] 'process raft request' (duration: 376.189797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.433923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.05714Z","time spent":"376.74191ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5011,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:35:51.684117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.212104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:35:51.684184Z","caller":"traceutil/trace.go:171","msg":"trace[328825200] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"241.644836ms","start":"2026-02-12T03:35:51.442526Z","end":"2026-02-12T03:35:51.68417Z","steps":["trace[328825200] 'process raft request' (duration: 37.323342ms)","trace[328825200] 'compare' (duration: 204.088783ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:51.887682Z","caller":"traceutil/trace.go:171","msg":"trace[1776077183] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"440.978992ms","start":"2026-02-12T03:35:51.446683Z","end":"2026-02-12T03:35:51.887662Z","steps":["trace[1776077183] 'process raft request' (duration: 440.936242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.887797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.446677Z","time spent":"441.066652ms","remote":"127.0.0.1:51814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:51.888018Z","caller":"traceutil/trace.go:171","msg":"trace[1175003927] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"443.432062ms","start":"2026-02-12T03:35:51.444576Z","end":"2026-02-12T03:35:51.888008Z","steps":["trace[1175003927] 'process raft request' (duration: 355.438562ms)","trace[1175003927] 'compare' (duration: 87.463858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:35:51.88808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.44457Z","time spent":"443.490002ms","remote":"127.0.0.1:51802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":783,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:35:51.956932Z","caller":"traceutil/trace.go:171","msg":"trace[1620671052] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:751; }","duration":"158.119025ms","start":"2026-02-12T03:35:51.798791Z","end":"2026-02-12T03:35:51.95691Z","steps":["trace[1620671052] 'read index received' (duration: 1.265824ms)","trace[1620671052] 'applied index is now lower than readState.Index' (duration: 156.852341ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:35:51.957051Z","caller":"traceutil/trace.go:171","msg":"trace[535765859] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"317.031204ms","start":"2026-02-12T03:35:51.640002Z","end":"2026-02-12T03:35:51.957033Z","steps":["trace[535765859] 'process raft request' (duration: 316.811193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:35:51.95714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:35:51.639986Z","time spent":"317.097464ms","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T03:35:51.957411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.614748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:35:51.957448Z","caller":"traceutil/trace.go:171","msg":"trace[665212440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"158.703169ms","start":"2026-02-12T03:35:51.798736Z","end":"2026-02-12T03:35:51.957439Z","steps":["trace[665212440] 'agreement among raft nodes before linearized reading' (duration: 158.625178ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:52.084116Z","caller":"traceutil/trace.go:171","msg":"trace[349949761] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"106.097863ms","start":"2026-02-12T03:35:51.978003Z","end":"2026-02-12T03:35:52.084101Z","steps":["trace[349949761] 'process raft request' (duration: 106.036352ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:35:52.084288Z","caller":"traceutil/trace.go:171","msg":"trace[296076091] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"109.982598ms","start":"2026-02-12T03:35:51.9743Z","end":"2026-02-12T03:35:52.084282Z","steps":["trace[296076091] 'process raft request' (duration: 107.722849ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:00.384481Z","caller":"traceutil/trace.go:171","msg":"trace[27374908] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"117.569987ms","start":"2026-02-12T03:36:00.266896Z","end":"2026-02-12T03:36:00.384466Z","steps":["trace[27374908] 'process raft request' (duration: 117.231136ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:01.267343Z","caller":"traceutil/trace.go:171","msg":"trace[1800068176] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"114.118433ms","start":"2026-02-12T03:36:01.152975Z","end":"2026-02-12T03:36:01.267093Z","steps":["trace[1800068176] 'process raft request' (duration: 113.852212ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:27.617233Z","caller":"traceutil/trace.go:171","msg":"trace[1368905832] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"103.965677ms","start":"2026-02-12T03:36:27.513249Z","end":"2026-02-12T03:36:27.617215Z","steps":["trace[1368905832] 'process raft request' (duration: 103.839457ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:36:32.734821Z","caller":"traceutil/trace.go:171","msg":"trace[2126047520] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"167.267002ms","start":"2026-02-12T03:36:32.567542Z","end":"2026-02-12T03:36:32.734809Z","steps":["trace[2126047520] 'process raft request' (duration: 167.204162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:33.184931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.908359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:36:33.18504Z","caller":"traceutil/trace.go:171","msg":"trace[2114916223] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"408.572321ms","start":"2026-02-12T03:36:32.776443Z","end":"2026-02-12T03:36:33.185015Z","steps":["trace[2114916223] 'read index received' (duration: 52.459051ms)","trace[2114916223] 'applied index is now lower than readState.Index' (duration: 356.11155ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:36:33.185157Z","caller":"traceutil/trace.go:171","msg":"trace[1541583482] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"436.834254ms","start":"2026-02-12T03:36:32.748308Z","end":"2026-02-12T03:36:33.185142Z","steps":["trace[1541583482] 'process raft request' (duration: 80.631114ms)","trace[1541583482] 'compare' (duration: 355.812009ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:36:33.185245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:32.748301Z","time spent":"436.890104ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:36:33.185417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.751385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:36:33.185546Z","caller":"traceutil/trace.go:171","msg":"trace[808359890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"384.915655ms","start":"2026-02-12T03:36:32.800596Z","end":"2026-02-12T03:36:33.185512Z","steps":["trace[808359890] 'agreement among raft nodes before linearized reading' (duration: 384.721185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:33.185599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:32.800585Z","time spent":"385.003986ms","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:36:33.185779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.285353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-02-12T03:36:33.185823Z","caller":"traceutil/trace.go:171","msg":"trace[458132684] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:881; }","duration":"409.397964ms","start":"2026-02-12T03:36:32.77641Z","end":"2026-02-12T03:36:33.185808Z","steps":["trace[458132684] 'agreement among raft nodes before linearized reading' (duration: 409.185453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:36:33.185858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:36:32.776393Z","time spent":"409.456384ms","remote":"127.0.0.1:52104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4220,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-12T03:36:50.934634Z","caller":"traceutil/trace.go:171","msg":"trace[32325296] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"108.073563ms","start":"2026-02-12T03:36:50.826544Z","end":"2026-02-12T03:36:50.934618Z","steps":["trace[32325296] 'process raft request' (duration: 107.970453ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:37:09.990687Z","caller":"traceutil/trace.go:171","msg":"trace[1435013392] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"229.743888ms","start":"2026-02-12T03:37:09.760923Z","end":"2026-02-12T03:37:09.990667Z","steps":["trace[1435013392] 'read index received' (duration: 229.550358ms)","trace[1435013392] 'applied index is now lower than readState.Index' (duration: 192.67µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:37:09.990823Z","caller":"traceutil/trace.go:171","msg":"trace[834586267] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"464.194485ms","start":"2026-02-12T03:37:09.526615Z","end":"2026-02-12T03:37:09.990809Z","steps":["trace[834586267] 'process raft request' (duration: 463.883404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:09.990923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:37:09.526596Z","time spent":"464.249635ms","remote":"127.0.0.1:51902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:37:09.991351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.438002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T03:37:09.991383Z","caller":"traceutil/trace.go:171","msg":"trace[13154621] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1019; }","duration":"230.467772ms","start":"2026-02-12T03:37:09.760907Z","end":"2026-02-12T03:37:09.991374Z","steps":["trace[13154621] 'agreement among raft nodes before linearized reading' (duration: 230.356042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:37:30.375316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.390275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T03:37:30.375367Z","caller":"traceutil/trace.go:171","msg":"trace[470333607] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1081; }","duration":"101.449845ms","start":"2026-02-12T03:37:30.273907Z","end":"2026-02-12T03:37:30.375357Z","steps":["trace[470333607] 'range keys from in-memory index tree' (duration: 101.292775ms)"],"step_count":1}