{"level":"warn","ts":"2026-02-26T01:40:03.499526Z","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-26T01:40:03.499632Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-26T01:40:03.49971Z","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-26T01:40:03.499723Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-26T01:40:03.49975Z","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-26T01:40:03.500427Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-02-26T01:40:03.500523Z","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":8,"max-cpu-available":8,"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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-26T01:40:03.505615Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.652515ms"} {"level":"info","ts":"2026-02-26T01:40:03.515039Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-02-26T01:40:03.515122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:40:03.51515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-02-26T01:40:03.515166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:40:03.515183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-02-26T01:40:03.51522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-02-26T01:40:03.526382Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:40:03.528098Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:40:03.529426Z","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-26T01:40:03.532477Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:40:03.53268Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:40:03.532881Z","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-26T01:40:03.532954Z","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-26T01:40:03.532965Z","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-26T01:40:03.533619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-02-26T01:40:03.533738Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-26T01:40:03.54014Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-26T01:40:03.540191Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-26T01:40:03.540267Z","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-26T01:40:03.54207Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:40:03.542017Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:40:04.016856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:40:04.016899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:40:04.016921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-02-26T01:40:04.016932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:40:04.016959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-26T01:40:04.016967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-02-26T01:40:04.016974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-26T01:40:04.018803Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:40:04.018842Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:40:04.018809Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:40:04.019127Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:40:04.019294Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:40:04.019384Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:40:04.019742Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:40:04.019891Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:40:04.01995Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:40:04.021845Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T01:40:04.022445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-02-26T01:40:09.174291Z","caller":"traceutil/trace.go:171","msg":"trace[1855052823] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:271; }","duration":"162.992406ms","start":"2026-02-26T01:40:09.011275Z","end":"2026-02-26T01:40:09.174267Z","steps":["trace[1855052823] 'read index received' (duration: 142.641232ms)","trace[1855052823] 'applied index is now lower than readState.Index' (duration: 20.350374ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:09.174461Z","caller":"traceutil/trace.go:171","msg":"trace[1938948244] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"187.759781ms","start":"2026-02-26T01:40:08.986678Z","end":"2026-02-26T01:40:09.174438Z","steps":["trace[1938948244] 'process raft request' (duration: 167.224605ms)","trace[1938948244] 'compare' (duration: 20.276213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:09.174475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.175908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:09.174537Z","caller":"traceutil/trace.go:171","msg":"trace[1758485556] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:266; }","duration":"163.279329ms","start":"2026-02-26T01:40:09.011245Z","end":"2026-02-26T01:40:09.174525Z","steps":["trace[1758485556] 'agreement among raft nodes before linearized reading' (duration: 163.144047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:09.174524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.203718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-26T01:40:09.174618Z","caller":"traceutil/trace.go:171","msg":"trace[2066365003] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:266; }","duration":"163.355149ms","start":"2026-02-26T01:40:09.01124Z","end":"2026-02-26T01:40:09.174595Z","steps":["trace[2066365003] 'agreement among raft nodes before linearized reading' (duration: 163.116157ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:09.178121Z","caller":"traceutil/trace.go:171","msg":"trace[1257020559] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"166.734097ms","start":"2026-02-26T01:40:09.011367Z","end":"2026-02-26T01:40:09.178102Z","steps":["trace[1257020559] 'process raft request' (duration: 166.327843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:09.178173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.154502ms","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-26T01:40:09.178225Z","caller":"traceutil/trace.go:171","msg":"trace[1773154462] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:267; }","duration":"138.249903ms","start":"2026-02-26T01:40:09.039959Z","end":"2026-02-26T01:40:09.178209Z","steps":["trace[1773154462] 'agreement among raft nodes before linearized reading' (duration: 138.152702ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:09.539375Z","caller":"traceutil/trace.go:171","msg":"trace[1696750360] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"215.788067ms","start":"2026-02-26T01:40:09.323566Z","end":"2026-02-26T01:40:09.539354Z","steps":["trace[1696750360] 'process raft request' (duration: 215.747127ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:09.539662Z","caller":"traceutil/trace.go:171","msg":"trace[1786934985] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"220.235926ms","start":"2026-02-26T01:40:09.319378Z","end":"2026-02-26T01:40:09.539614Z","steps":["trace[1786934985] 'process raft request' (duration: 219.899073ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:09.539637Z","caller":"traceutil/trace.go:171","msg":"trace[944054000] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"223.747455ms","start":"2026-02-26T01:40:09.315873Z","end":"2026-02-26T01:40:09.539621Z","steps":["trace[944054000] 'process raft request' (duration: 159.73631ms)","trace[944054000] 'compare' (duration: 63.473479ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:09.539683Z","caller":"traceutil/trace.go:171","msg":"trace[481651025] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"223.116048ms","start":"2026-02-26T01:40:09.31656Z","end":"2026-02-26T01:40:09.539676Z","steps":["trace[481651025] 'process raft request' (duration: 222.659953ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:13.199374Z","caller":"traceutil/trace.go:171","msg":"trace[2139474568] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"105.404793ms","start":"2026-02-26T01:40:13.093947Z","end":"2026-02-26T01:40:13.199351Z","steps":["trace[2139474568] 'read index received' (duration: 105.215301ms)","trace[2139474568] 'applied index is now lower than readState.Index' (duration: 188.532µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:13.199489Z","caller":"traceutil/trace.go:171","msg":"trace[855451787] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"111.302685ms","start":"2026-02-26T01:40:13.088154Z","end":"2026-02-26T01:40:13.199457Z","steps":["trace[855451787] 'process raft request' (duration: 111.074583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:13.19954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.568594ms","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-26T01:40:13.199588Z","caller":"traceutil/trace.go:171","msg":"trace[1342024795] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:339; }","duration":"105.662375ms","start":"2026-02-26T01:40:13.093912Z","end":"2026-02-26T01:40:13.199574Z","steps":["trace[1342024795] 'agreement among raft nodes before linearized reading' (duration: 105.553134ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:13.693396Z","caller":"traceutil/trace.go:171","msg":"trace[1405559290] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"137.005416ms","start":"2026-02-26T01:40:13.556317Z","end":"2026-02-26T01:40:13.693323Z","steps":["trace[1405559290] 'process raft request' (duration: 136.828214ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:13.807323Z","caller":"traceutil/trace.go:171","msg":"trace[1799224281] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"105.456823ms","start":"2026-02-26T01:40:13.701832Z","end":"2026-02-26T01:40:13.807289Z","steps":["trace[1799224281] 'process raft request' (duration: 73.389375ms)","trace[1799224281] 'compare' (duration: 31.921857ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:13.852618Z","caller":"traceutil/trace.go:171","msg":"trace[810007464] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:350; }","duration":"107.883398ms","start":"2026-02-26T01:40:13.744702Z","end":"2026-02-26T01:40:13.852585Z","steps":["trace[810007464] 'read index received' (duration: 30.741094ms)","trace[810007464] 'applied index is now lower than readState.Index' (duration: 77.140954ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:13.852712Z","caller":"traceutil/trace.go:171","msg":"trace[1503777986] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"149.965794ms","start":"2026-02-26T01:40:13.702723Z","end":"2026-02-26T01:40:13.852689Z","steps":["trace[1503777986] 'process raft request' (duration: 149.61264ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:13.852763Z","caller":"traceutil/trace.go:171","msg":"trace[1950044836] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"148.069543ms","start":"2026-02-26T01:40:13.704682Z","end":"2026-02-26T01:40:13.852751Z","steps":["trace[1950044836] 'process raft request' (duration: 147.80355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:13.853149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.393844ms","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-26T01:40:13.853289Z","caller":"traceutil/trace.go:171","msg":"trace[1407343915] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"108.581737ms","start":"2026-02-26T01:40:13.744676Z","end":"2026-02-26T01:40:13.853258Z","steps":["trace[1407343915] 'agreement among raft nodes before linearized reading' (duration: 108.298554ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.0389Z","caller":"traceutil/trace.go:171","msg":"trace[1022350080] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"180.269569ms","start":"2026-02-26T01:40:13.858596Z","end":"2026-02-26T01:40:14.038866Z","steps":["trace[1022350080] 'process raft request' (duration: 168.369825ms)","trace[1022350080] 'compare' (duration: 11.684382ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:14.065204Z","caller":"traceutil/trace.go:171","msg":"trace[2043522405] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"201.969686ms","start":"2026-02-26T01:40:13.86321Z","end":"2026-02-26T01:40:14.06518Z","steps":["trace[2043522405] 'process raft request' (duration: 201.845745ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.065193Z","caller":"traceutil/trace.go:171","msg":"trace[930744424] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"202.069897ms","start":"2026-02-26T01:40:13.863085Z","end":"2026-02-26T01:40:14.065155Z","steps":["trace[930744424] 'process raft request' (duration: 201.836044ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.065589Z","caller":"traceutil/trace.go:171","msg":"trace[1675322430] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"202.338739ms","start":"2026-02-26T01:40:13.86324Z","end":"2026-02-26T01:40:14.065579Z","steps":["trace[1675322430] 'process raft request' (duration: 201.858714ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.065708Z","caller":"traceutil/trace.go:171","msg":"trace[382844430] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"202.42325ms","start":"2026-02-26T01:40:13.863249Z","end":"2026-02-26T01:40:14.065673Z","steps":["trace[382844430] 'process raft request' (duration: 202.167647ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.065922Z","caller":"traceutil/trace.go:171","msg":"trace[1273908975] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"202.618753ms","start":"2026-02-26T01:40:13.863288Z","end":"2026-02-26T01:40:14.065907Z","steps":["trace[1273908975] 'process raft request' (duration: 202.294179ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.065867Z","caller":"traceutil/trace.go:171","msg":"trace[1258052452] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:353; }","duration":"171.19676ms","start":"2026-02-26T01:40:13.894611Z","end":"2026-02-26T01:40:14.065808Z","steps":["trace[1258052452] 'read index received' (duration: 132.373185ms)","trace[1258052452] 'applied index is now lower than readState.Index' (duration: 38.821885ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:14.065957Z","caller":"traceutil/trace.go:171","msg":"trace[490290695] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"200.166797ms","start":"2026-02-26T01:40:13.865776Z","end":"2026-02-26T01:40:14.065943Z","steps":["trace[490290695] 'process raft request' (duration: 199.930864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.066218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.582624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-26T01:40:14.06739Z","caller":"traceutil/trace.go:171","msg":"trace[1564541611] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:351; }","duration":"172.819528ms","start":"2026-02-26T01:40:13.894546Z","end":"2026-02-26T01:40:14.067365Z","steps":["trace[1564541611] 'agreement among raft nodes before linearized reading' (duration: 171.480144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.066249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.800729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-26T01:40:14.067559Z","caller":"traceutil/trace.go:171","msg":"trace[1782598699] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:351; }","duration":"123.159753ms","start":"2026-02-26T01:40:13.944388Z","end":"2026-02-26T01:40:14.067548Z","steps":["trace[1782598699] 'agreement among raft nodes before linearized reading' (duration: 121.667057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.385707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.626669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:40:14.385872Z","caller":"traceutil/trace.go:171","msg":"trace[611423867] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"311.0357ms","start":"2026-02-26T01:40:14.074809Z","end":"2026-02-26T01:40:14.385845Z","steps":["trace[611423867] 'process raft request' (duration: 82.33062ms)","trace[611423867] 'compare' (duration: 227.430007ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:14.385951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.074786Z","time spent":"311.131702ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:14.554805Z","caller":"traceutil/trace.go:171","msg":"trace[301465298] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"479.860785ms","start":"2026-02-26T01:40:14.074907Z","end":"2026-02-26T01:40:14.554768Z","steps":["trace[301465298] 'process raft request' (duration: 479.514751ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.554843Z","caller":"traceutil/trace.go:171","msg":"trace[978781515] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"479.198318ms","start":"2026-02-26T01:40:14.075621Z","end":"2026-02-26T01:40:14.55482Z","steps":["trace[978781515] 'process raft request' (duration: 479.049537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.554981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.075608Z","time spent":"479.297159ms","remote":"127.0.0.1:39892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:40:14.554996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.074883Z","time spent":"480.022937ms","remote":"127.0.0.1:39678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:40:14.554922Z","caller":"traceutil/trace.go:171","msg":"trace[1217152860] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"478.710783ms","start":"2026-02-26T01:40:14.076188Z","end":"2026-02-26T01:40:14.554898Z","steps":["trace[1217152860] 'process raft request' (duration: 478.542161ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:14.555064Z","caller":"traceutil/trace.go:171","msg":"trace[1160135584] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"462.963328ms","start":"2026-02-26T01:40:14.092062Z","end":"2026-02-26T01:40:14.555025Z","steps":["trace[1160135584] 'process raft request' (duration: 462.740376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.555175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.076177Z","time spent":"478.929985ms","remote":"127.0.0.1:39750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:40:14.555214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.092048Z","time spent":"463.09245ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:40:14.555388Z","caller":"traceutil/trace.go:171","msg":"trace[449643470] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"463.1199ms","start":"2026-02-26T01:40:14.092254Z","end":"2026-02-26T01:40:14.555374Z","steps":["trace[449643470] 'process raft request' (duration: 462.598875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.555483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.092244Z","time spent":"463.178001ms","remote":"127.0.0.1:39868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:14.555502Z","caller":"traceutil/trace.go:171","msg":"trace[778659124] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"462.540834ms","start":"2026-02-26T01:40:14.092943Z","end":"2026-02-26T01:40:14.555484Z","steps":["trace[778659124] 'process raft request' (duration: 461.969888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.555609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.092933Z","time spent":"462.614665ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:14.555729Z","caller":"traceutil/trace.go:171","msg":"trace[4122499] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"480.822105ms","start":"2026-02-26T01:40:14.074889Z","end":"2026-02-26T01:40:14.555712Z","steps":["trace[4122499] 'process raft request' (duration: 454.714232ms)","trace[4122499] 'compare' (duration: 24.662338ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:14.555766Z","caller":"traceutil/trace.go:171","msg":"trace[1890548497] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:360; }","duration":"480.442031ms","start":"2026-02-26T01:40:14.075303Z","end":"2026-02-26T01:40:14.555745Z","steps":["trace[1890548497] 'read index received' (duration: 81.773415ms)","trace[1890548497] 'applied index is now lower than readState.Index' (duration: 398.665666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:14.555925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.487463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-26T01:40:14.555976Z","caller":"traceutil/trace.go:171","msg":"trace[859553778] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:359; }","duration":"461.581034ms","start":"2026-02-26T01:40:14.094378Z","end":"2026-02-26T01:40:14.555959Z","steps":["trace[859553778] 'agreement among raft nodes before linearized reading' (duration: 461.489373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.556024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.094361Z","time spent":"461.651085ms","remote":"127.0.0.1:39786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-26T01:40:14.55627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.097767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-26T01:40:14.556317Z","caller":"traceutil/trace.go:171","msg":"trace[2082487548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:359; }","duration":"303.211999ms","start":"2026-02-26T01:40:14.253091Z","end":"2026-02-26T01:40:14.556303Z","steps":["trace[2082487548] 'agreement among raft nodes before linearized reading' (duration: 303.126128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.556356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.253063Z","time spent":"303.281499ms","remote":"127.0.0.1:39882","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-26T01:40:14.556569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.046578ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:14.556664Z","caller":"traceutil/trace.go:171","msg":"trace[52251205] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:359; }","duration":"305.145129ms","start":"2026-02-26T01:40:14.251505Z","end":"2026-02-26T01:40:14.556651Z","steps":["trace[52251205] 'agreement among raft nodes before linearized reading' (duration: 305.026318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.557087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.551322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-02-26T01:40:14.557709Z","caller":"traceutil/trace.go:171","msg":"trace[1258275061] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:359; }","duration":"482.410431ms","start":"2026-02-26T01:40:14.075283Z","end":"2026-02-26T01:40:14.557693Z","steps":["trace[1258275061] 'agreement among raft nodes before linearized reading' (duration: 480.539702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.557776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.075271Z","time spent":"482.490063ms","remote":"127.0.0.1:40054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-26T01:40:14.558157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.07487Z","time spent":"480.919456ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:14.559162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.969976ms","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-26T01:40:14.564601Z","caller":"traceutil/trace.go:171","msg":"trace[1527327301] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:359; }","duration":"415.270289ms","start":"2026-02-26T01:40:14.143965Z","end":"2026-02-26T01:40:14.559235Z","steps":["trace[1527327301] 'agreement among raft nodes before linearized reading' (duration: 412.931355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:14.564698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.143953Z","time spent":"420.710157ms","remote":"127.0.0.1:39786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-26T01:40:15.273649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.78036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:40:15.273796Z","caller":"traceutil/trace.go:171","msg":"trace[1091382035] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"710.530858ms","start":"2026-02-26T01:40:14.563239Z","end":"2026-02-26T01:40:15.27377Z","steps":["trace[1091382035] 'process raft request' (duration: 288.531796ms)","trace[1091382035] 'compare' (duration: 421.580088ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:15.273922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.563218Z","time spent":"710.675899ms","remote":"127.0.0.1:39678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:40:15.281775Z","caller":"traceutil/trace.go:171","msg":"trace[753060399] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"717.939275ms","start":"2026-02-26T01:40:14.563809Z","end":"2026-02-26T01:40:15.281748Z","steps":["trace[753060399] 'process raft request' (duration: 717.675392ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.281968Z","caller":"traceutil/trace.go:171","msg":"trace[1138542933] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"716.917494ms","start":"2026-02-26T01:40:14.565014Z","end":"2026-02-26T01:40:15.281931Z","steps":["trace[1138542933] 'process raft request' (duration: 716.736352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.282158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.563792Z","time spent":"718.209277ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2824,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:15.282196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.565008Z","time spent":"717.031025ms","remote":"127.0.0.1:40054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:15.28226Z","caller":"traceutil/trace.go:171","msg":"trace[325874165] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"717.017155ms","start":"2026-02-26T01:40:14.565223Z","end":"2026-02-26T01:40:15.28224Z","steps":["trace[325874165] 'process raft request' (duration: 716.57912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.282404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.565213Z","time spent":"717.113126ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:15.282481Z","caller":"traceutil/trace.go:171","msg":"trace[1607989035] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"714.986043ms","start":"2026-02-26T01:40:14.567475Z","end":"2026-02-26T01:40:15.282461Z","steps":["trace[1607989035] 'process raft request' (duration: 714.71013ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.282611Z","caller":"traceutil/trace.go:171","msg":"trace[1504075733] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"717.636352ms","start":"2026-02-26T01:40:14.564958Z","end":"2026-02-26T01:40:15.282594Z","steps":["trace[1504075733] 'process raft request' (duration: 716.716262ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.282636Z","caller":"traceutil/trace.go:171","msg":"trace[1525243841] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"717.046275ms","start":"2026-02-26T01:40:14.565573Z","end":"2026-02-26T01:40:15.282619Z","steps":["trace[1525243841] 'process raft request' (duration: 716.474479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.282702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.565562Z","time spent":"717.110635ms","remote":"127.0.0.1:39786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:40:15.282733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.564948Z","time spent":"717.723172ms","remote":"127.0.0.1:39868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:15.282796Z","caller":"traceutil/trace.go:171","msg":"trace[1781675421] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"717.295158ms","start":"2026-02-26T01:40:14.565492Z","end":"2026-02-26T01:40:15.282787Z","steps":["trace[1781675421] 'process raft request' (duration: 716.366448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.282861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.565484Z","time spent":"717.346398ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:15.282885Z","caller":"traceutil/trace.go:171","msg":"trace[1580802492] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"717.288328ms","start":"2026-02-26T01:40:14.565585Z","end":"2026-02-26T01:40:15.282873Z","steps":["trace[1580802492] 'process raft request' (duration: 716.55469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.282626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.567464Z","time spent":"715.104635ms","remote":"127.0.0.1:39954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:15.282948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.565577Z","time spent":"717.337228ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:15.283238Z","caller":"traceutil/trace.go:171","msg":"trace[299188774] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:368; }","duration":"716.267417ms","start":"2026-02-26T01:40:14.566956Z","end":"2026-02-26T01:40:15.283224Z","steps":["trace[299188774] 'read index received' (duration: 284.826497ms)","trace[299188774] 'applied index is now lower than readState.Index' (duration: 431.43885ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:15.283341Z","caller":"traceutil/trace.go:171","msg":"trace[1580043703] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"707.388534ms","start":"2026-02-26T01:40:14.575944Z","end":"2026-02-26T01:40:15.283332Z","steps":["trace[1580043703] 'process raft request' (duration: 706.444574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.283422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.575931Z","time spent":"707.440015ms","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:40:15.283438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.476989ms","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-26T01:40:15.283473Z","caller":"traceutil/trace.go:171","msg":"trace[64256746] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:369; }","duration":"716.53885ms","start":"2026-02-26T01:40:14.566925Z","end":"2026-02-26T01:40:15.283464Z","steps":["trace[64256746] 'agreement among raft nodes before linearized reading' (duration: 716.423528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.283495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.566914Z","time spent":"716.57559ms","remote":"127.0.0.1:39786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-26T01:40:15.329944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.54752ms","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-26T01:40:15.330018Z","caller":"traceutil/trace.go:171","msg":"trace[2112792501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"126.66166ms","start":"2026-02-26T01:40:15.203341Z","end":"2026-02-26T01:40:15.330002Z","steps":["trace[2112792501] 'agreement among raft nodes before linearized reading' (duration: 126.507959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.329962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.041449ms","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-26T01:40:15.330205Z","caller":"traceutil/trace.go:171","msg":"trace[852301464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:370; }","duration":"380.344691ms","start":"2026-02-26T01:40:14.949841Z","end":"2026-02-26T01:40:15.330186Z","steps":["trace[852301464] 'agreement among raft nodes before linearized reading' (duration: 380.005518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.330291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.94982Z","time spent":"380.456162ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:40:15.330464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.624847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:15.330538Z","caller":"traceutil/trace.go:171","msg":"trace[1206021854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"564.746179ms","start":"2026-02-26T01:40:14.765773Z","end":"2026-02-26T01:40:15.330519Z","steps":["trace[1206021854] 'agreement among raft nodes before linearized reading' (duration: 564.410325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.330574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:14.765753Z","time spent":"564.813629ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:40:15.542434Z","caller":"traceutil/trace.go:171","msg":"trace[1532032795] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"250.518392ms","start":"2026-02-26T01:40:15.291887Z","end":"2026-02-26T01:40:15.542405Z","steps":["trace[1532032795] 'process raft request' (duration: 177.678398ms)","trace[1532032795] 'compare' (duration: 72.36432ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:15.542413Z","caller":"traceutil/trace.go:171","msg":"trace[733376467] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:379; }","duration":"212.59767ms","start":"2026-02-26T01:40:15.329784Z","end":"2026-02-26T01:40:15.542382Z","steps":["trace[733376467] 'read index received' (duration: 139.855217ms)","trace[733376467] 'applied index is now lower than readState.Index' (duration: 72.740803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:15.542852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.400691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-02-26T01:40:15.542922Z","caller":"traceutil/trace.go:171","msg":"trace[28569212] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:374; }","duration":"251.745986ms","start":"2026-02-26T01:40:15.291161Z","end":"2026-02-26T01:40:15.542907Z","steps":["trace[28569212] 'agreement among raft nodes before linearized reading' (duration: 251.325161ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.542975Z","caller":"traceutil/trace.go:171","msg":"trace[1600565321] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"246.315279ms","start":"2026-02-26T01:40:15.296642Z","end":"2026-02-26T01:40:15.542957Z","steps":["trace[1600565321] 'process raft request' (duration: 245.596772ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.543127Z","caller":"traceutil/trace.go:171","msg":"trace[963875863] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"246.43856ms","start":"2026-02-26T01:40:15.296644Z","end":"2026-02-26T01:40:15.543082Z","steps":["trace[963875863] 'process raft request' (duration: 245.648082ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.543262Z","caller":"traceutil/trace.go:171","msg":"trace[903642609] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"250.875796ms","start":"2026-02-26T01:40:15.292343Z","end":"2026-02-26T01:40:15.543219Z","steps":["trace[903642609] 'process raft request' (duration: 249.831315ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.581974Z","caller":"traceutil/trace.go:171","msg":"trace[1152507436] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"248.477651ms","start":"2026-02-26T01:40:15.333428Z","end":"2026-02-26T01:40:15.581906Z","steps":["trace[1152507436] 'process raft request' (duration: 248.230839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.582081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.24793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:15.582208Z","caller":"traceutil/trace.go:171","msg":"trace[1610268629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"250.416202ms","start":"2026-02-26T01:40:15.33175Z","end":"2026-02-26T01:40:15.582166Z","steps":["trace[1610268629] 'agreement among raft nodes before linearized reading' (duration: 250.22738ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.582291Z","caller":"traceutil/trace.go:171","msg":"trace[2052105012] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"248.180598ms","start":"2026-02-26T01:40:15.334088Z","end":"2026-02-26T01:40:15.582269Z","steps":["trace[2052105012] 'process raft request' (duration: 247.729393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:15.582462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.320396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-26T01:40:15.582486Z","caller":"traceutil/trace.go:171","msg":"trace[1865594359] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"247.414061ms","start":"2026-02-26T01:40:15.335041Z","end":"2026-02-26T01:40:15.582455Z","steps":["trace[1865594359] 'process raft request' (duration: 246.855565ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:15.582541Z","caller":"traceutil/trace.go:171","msg":"trace[407534559] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:377; }","duration":"123.460328ms","start":"2026-02-26T01:40:15.459066Z","end":"2026-02-26T01:40:15.582526Z","steps":["trace[407534559] 'agreement among raft nodes before linearized reading' (duration: 123.176035ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:18.719996Z","caller":"traceutil/trace.go:171","msg":"trace[1848884909] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"293.661674ms","start":"2026-02-26T01:40:18.426316Z","end":"2026-02-26T01:40:18.719978Z","steps":["trace[1848884909] 'process raft request' (duration: 293.550433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:19.446613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.620281ms","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-26T01:40:19.446729Z","caller":"traceutil/trace.go:171","msg":"trace[428108873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"145.810534ms","start":"2026-02-26T01:40:19.300886Z","end":"2026-02-26T01:40:19.446697Z","steps":["trace[428108873] 'range keys from in-memory index tree' (duration: 145.42453ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:19.637196Z","caller":"traceutil/trace.go:171","msg":"trace[1433762155] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"185.772322ms","start":"2026-02-26T01:40:19.451381Z","end":"2026-02-26T01:40:19.637153Z","steps":["trace[1433762155] 'process raft request' (duration: 142.291449ms)","trace[1433762155] 'compare' (duration: 43.271681ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:28.266492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530668535103204,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:40:28.392913Z","caller":"traceutil/trace.go:171","msg":"trace[1211937049] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"682.429915ms","start":"2026-02-26T01:40:27.710448Z","end":"2026-02-26T01:40:28.392878Z","steps":["trace[1211937049] 'process raft request' (duration: 682.191943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:28.393172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:27.710431Z","time spent":"682.601987ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:29.175308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.030157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:40:29.17546Z","caller":"traceutil/trace.go:171","msg":"trace[319168284] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:474; }","duration":"1.410001615s","start":"2026-02-26T01:40:27.765427Z","end":"2026-02-26T01:40:29.175429Z","steps":["trace[319168284] 'read index received' (duration: 627.183889ms)","trace[319168284] 'applied index is now lower than readState.Index' (duration: 782.812516ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:29.175539Z","caller":"traceutil/trace.go:171","msg":"trace[1103362500] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"1.462392915s","start":"2026-02-26T01:40:27.713096Z","end":"2026-02-26T01:40:29.175489Z","steps":["trace[1103362500] 'process raft request' (duration: 1.281039665s)","trace[1103362500] 'compare' (duration: 180.865076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:29.175614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410156767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:40:29.175628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264303406s","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-26T01:40:29.175677Z","caller":"traceutil/trace.go:171","msg":"trace[1965325493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"1.264388047s","start":"2026-02-26T01:40:27.911274Z","end":"2026-02-26T01:40:29.175662Z","steps":["trace[1965325493] 'agreement among raft nodes before linearized reading' (duration: 1.264300306s)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:29.17568Z","caller":"traceutil/trace.go:171","msg":"trace[1749366030] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"1.410272809s","start":"2026-02-26T01:40:27.765385Z","end":"2026-02-26T01:40:29.175658Z","steps":["trace[1749366030] 'agreement among raft nodes before linearized reading' (duration: 1.410154307s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:29.17573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:27.911253Z","time spent":"1.264466997s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:40:29.175733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:27.765367Z","time spent":"1.410353039s","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:40:29.175765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:27.713081Z","time spent":"1.462556466s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:34.65517Z","caller":"traceutil/trace.go:171","msg":"trace[897666786] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"236.143933ms","start":"2026-02-26T01:40:34.419001Z","end":"2026-02-26T01:40:34.655145Z","steps":["trace[897666786] 'process raft request' (duration: 236.022622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:35.888763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.17963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T01:40:35.888858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.138564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-02-26T01:40:35.888903Z","caller":"traceutil/trace.go:171","msg":"trace[1155480821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"592.374663ms","start":"2026-02-26T01:40:35.296499Z","end":"2026-02-26T01:40:35.888873Z","steps":["trace[1155480821] 'range keys from in-memory index tree' (duration: 592.07116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:35.888982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:35.296478Z","time spent":"592.472073ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:40:35.88864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.888121ms","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-26T01:40:35.889098Z","caller":"traceutil/trace.go:171","msg":"trace[1635628497] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:474; }","duration":"363.280955ms","start":"2026-02-26T01:40:35.525641Z","end":"2026-02-26T01:40:35.888922Z","steps":["trace[1635628497] 'range keys from in-memory index tree' (duration: 363.042653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:35.889166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:35.525624Z","time spent":"363.528057ms","remote":"127.0.0.1:39674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12697,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-26T01:40:35.88923Z","caller":"traceutil/trace.go:171","msg":"trace[891005777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"311.343015ms","start":"2026-02-26T01:40:35.577709Z","end":"2026-02-26T01:40:35.889052Z","steps":["trace[891005777] 'range keys from in-memory index tree' (duration: 310.798791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:35.889385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:35.57768Z","time spent":"311.666628ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:40:38.958292Z","caller":"traceutil/trace.go:171","msg":"trace[562954706] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"278.045568ms","start":"2026-02-26T01:40:38.680219Z","end":"2026-02-26T01:40:38.958265Z","steps":["trace[562954706] 'process raft request' (duration: 277.845517ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:39.109135Z","caller":"traceutil/trace.go:171","msg":"trace[1005143802] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"133.188283ms","start":"2026-02-26T01:40:38.975927Z","end":"2026-02-26T01:40:39.109115Z","steps":["trace[1005143802] 'process raft request' (duration: 133.046492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:45.154408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.069918ms","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-26T01:40:45.154466Z","caller":"traceutil/trace.go:171","msg":"trace[1209509209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"177.170128ms","start":"2026-02-26T01:40:44.977284Z","end":"2026-02-26T01:40:45.154454Z","steps":["trace[1209509209] 'range keys from in-memory index tree' (duration: 176.958666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:45.750997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530668535103402,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T01:40:45.978317Z","caller":"traceutil/trace.go:171","msg":"trace[1607223115] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"727.729656ms","start":"2026-02-26T01:40:45.250562Z","end":"2026-02-26T01:40:45.978292Z","steps":["trace[1607223115] 'read index received' (duration: 727.497524ms)","trace[1607223115] 'applied index is now lower than readState.Index' (duration: 231.062µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:45.978418Z","caller":"traceutil/trace.go:171","msg":"trace[188675727] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"808.849946ms","start":"2026-02-26T01:40:45.169537Z","end":"2026-02-26T01:40:45.978387Z","steps":["trace[188675727] 'process raft request' (duration: 808.611504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:45.978962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:45.169514Z","time spent":"808.958497ms","remote":"127.0.0.1:39766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:45.979139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.550862ms","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-26T01:40:45.979229Z","caller":"traceutil/trace.go:171","msg":"trace[1773310607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"728.672914ms","start":"2026-02-26T01:40:45.250531Z","end":"2026-02-26T01:40:45.979204Z","steps":["trace[1773310607] 'agreement among raft nodes before linearized reading' (duration: 727.889948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:45.979308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:45.250514Z","time spent":"728.772815ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T01:40:45.979577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.966095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:45.979623Z","caller":"traceutil/trace.go:171","msg":"trace[1019167009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"214.042107ms","start":"2026-02-26T01:40:45.765567Z","end":"2026-02-26T01:40:45.979609Z","steps":["trace[1019167009] 'agreement among raft nodes before linearized reading' (duration: 212.897287ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:46.240445Z","caller":"traceutil/trace.go:171","msg":"trace[883968096] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"254.97566ms","start":"2026-02-26T01:40:45.98543Z","end":"2026-02-26T01:40:46.240406Z","steps":["trace[883968096] 'process raft request' (duration: 254.362005ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:46.292522Z","caller":"traceutil/trace.go:171","msg":"trace[1521805853] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"305.903176ms","start":"2026-02-26T01:40:45.986588Z","end":"2026-02-26T01:40:46.292491Z","steps":["trace[1521805853] 'process raft request' (duration: 305.854086ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:46.292565Z","caller":"traceutil/trace.go:171","msg":"trace[1554456727] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"306.794414ms","start":"2026-02-26T01:40:45.985741Z","end":"2026-02-26T01:40:46.292536Z","steps":["trace[1554456727] 'process raft request' (duration: 306.542411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:46.292693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:45.98658Z","time spent":"306.029177ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:46.293403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:45.985719Z","time spent":"306.904044ms","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:46.964897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.789742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:46.964972Z","caller":"traceutil/trace.go:171","msg":"trace[943441265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"197.935663ms","start":"2026-02-26T01:40:46.767019Z","end":"2026-02-26T01:40:46.964955Z","steps":["trace[943441265] 'range keys from in-memory index tree' (duration: 197.663111ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:40:46.971065Z","caller":"traceutil/trace.go:171","msg":"trace[36335334] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"121.698657ms","start":"2026-02-26T01:40:46.849333Z","end":"2026-02-26T01:40:46.971032Z","steps":["trace[36335334] 'process raft request' (duration: 121.504805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:47.873103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530668535103413,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:40:48.273795Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.076322071s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T01:40:48.274311Z","caller":"traceutil/trace.go:171","msg":"trace[1274851722] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"901.556117ms","start":"2026-02-26T01:40:47.37273Z","end":"2026-02-26T01:40:48.274286Z","steps":["trace[1274851722] 'read index received' (duration: 901.237675ms)","trace[1274851722] 'applied index is now lower than readState.Index' (duration: 317.362µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:40:48.274623Z","caller":"traceutil/trace.go:171","msg":"trace[391679794] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.077203398s","start":"2026-02-26T01:40:47.197377Z","end":"2026-02-26T01:40:48.274581Z","steps":["trace[391679794] 'process raft request' (duration: 1.076672993s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:48.274665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.450718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:48.274764Z","caller":"traceutil/trace.go:171","msg":"trace[2117590737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"508.62115ms","start":"2026-02-26T01:40:47.766124Z","end":"2026-02-26T01:40:48.274745Z","steps":["trace[2117590737] 'agreement among raft nodes before linearized reading' (duration: 508.437009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:48.274819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:47.197358Z","time spent":"1.07734889s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:40:48.274825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:47.766102Z","time spent":"508.713881ms","remote":"127.0.0.1:39578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T01:40:48.274661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.90744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T01:40:48.274949Z","caller":"traceutil/trace.go:171","msg":"trace[297013784] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:562; }","duration":"902.230832ms","start":"2026-02-26T01:40:47.372678Z","end":"2026-02-26T01:40:48.274909Z","steps":["trace[297013784] 'agreement among raft nodes before linearized reading' (duration: 901.808589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:48.275052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:47.372658Z","time spent":"902.378793ms","remote":"127.0.0.1:39592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-02-26T01:40:48.796011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530668535103416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:40:49.296493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530668535103416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:40:49.66614Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.38829648s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-26T01:40:49.671441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.629539ms","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-26T01:40:49.671565Z","caller":"traceutil/trace.go:171","msg":"trace[2052309926] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:562; }","duration":"146.77382ms","start":"2026-02-26T01:40:49.524773Z","end":"2026-02-26T01:40:49.671546Z","steps":["trace[2052309926] 'range keys from in-memory index tree' (duration: 146.612709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:49.671569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:48.277673Z","time spent":"1.393890306s","remote":"127.0.0.1:39592","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-26T01:40:49.696584Z","caller":"traceutil/trace.go:171","msg":"trace[1148270311] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.416046109s","start":"2026-02-26T01:40:48.280526Z","end":"2026-02-26T01:40:49.696572Z","steps":["trace[1148270311] 'process raft request' (duration: 1.415901617s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:49.696683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:48.280505Z","time spent":"1.416119549s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:40:49.696894Z","caller":"traceutil/trace.go:171","msg":"trace[1454974330] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:580; }","duration":"1.400703461s","start":"2026-02-26T01:40:48.29583Z","end":"2026-02-26T01:40:49.696534Z","steps":["trace[1454974330] 'read index received' (duration: 1.370601033s)","trace[1454974330] 'applied index is now lower than readState.Index' (duration: 30.101718ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:40:49.697022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401157505s","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-26T01:40:49.697105Z","caller":"traceutil/trace.go:171","msg":"trace[317187374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"1.401272397s","start":"2026-02-26T01:40:48.295789Z","end":"2026-02-26T01:40:49.697061Z","steps":["trace[317187374] 'agreement among raft nodes before linearized reading' (duration: 1.400949924s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:49.697149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:48.295778Z","time spent":"1.401360817s","remote":"127.0.0.1:39882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:40:49.697808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401341397s","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-26T01:40:49.697866Z","caller":"traceutil/trace.go:171","msg":"trace[1199421704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"1.401419417s","start":"2026-02-26T01:40:48.296433Z","end":"2026-02-26T01:40:49.697853Z","steps":["trace[1199421704] 'agreement among raft nodes before linearized reading' (duration: 1.401274796s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:49.69807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.936395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:40:49.698142Z","caller":"traceutil/trace.go:171","msg":"trace[381499253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"918.077585ms","start":"2026-02-26T01:40:48.780046Z","end":"2026-02-26T01:40:49.698123Z","steps":["trace[381499253] 'agreement among raft nodes before linearized reading' (duration: 917.950334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:49.698303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:48.29642Z","time spent":"1.401869371s","remote":"127.0.0.1:39882","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-26T01:40:49.698261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:48.780017Z","time spent":"918.232447ms","remote":"127.0.0.1:39576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:40:51.7111Z","caller":"traceutil/trace.go:171","msg":"trace[1189888151] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"428.447444ms","start":"2026-02-26T01:40:51.282628Z","end":"2026-02-26T01:40:51.711075Z","steps":["trace[1189888151] 'process raft request' (duration: 428.265763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:51.711323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:40:51.282603Z","time spent":"428.589925ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:40:51.716694Z","caller":"traceutil/trace.go:171","msg":"trace[232108772] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"235.310914ms","start":"2026-02-26T01:40:51.481368Z","end":"2026-02-26T01:40:51.716679Z","steps":["trace[232108772] 'process raft request' (duration: 235.132962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:40:54.477149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.009209ms","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-26T01:40:54.477234Z","caller":"traceutil/trace.go:171","msg":"trace[1018460895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"127.110863ms","start":"2026-02-26T01:40:54.350103Z","end":"2026-02-26T01:40:54.477214Z","steps":["trace[1018460895] 'range keys from in-memory index tree' (duration: 126.81047ms)"],"step_count":1}