{"level":"warn","ts":"2026-02-05T22:07:29.468098Z","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-05T22:07:29.468253Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-05T22:07:29.468347Z","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-05T22:07:29.468385Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-05T22:07:29.468426Z","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-05T22:07:29.469045Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-05T22:07:29.469156Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-05T22:07:29.626711Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"157.31334ms"} {"level":"info","ts":"2026-02-05T22:07:29.835408Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-05T22:07:29.835606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T22:07:29.835653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-05T22:07:29.835676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T22:07:29.835699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-05T22:07:29.835774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-05T22:07:30.094895Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T22:07:30.156669Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T22:07:30.304693Z","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-05T22:07:30.37002Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T22:07:30.374499Z","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-05T22:07:30.374558Z","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-05T22:07:30.374568Z","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-05T22:07:30.374723Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T22:07:30.37581Z","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-05T22:07:30.375944Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-05T22:07:30.376038Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-05T22:07:30.378393Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T22:07:30.37844Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T22:07:30.398636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-05T22:07:30.398799Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-05T22:07:30.968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T22:07:30.968049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T22:07:30.968068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-05T22:07:30.968079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-05T22:07:30.968093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-05T22:07:30.968113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-05T22:07:30.968126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-05T22:07:31.015677Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:07:31.107787Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T22:07:31.107972Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:07:31.108734Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:07:31.11203Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T22:07:31.112259Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T22:07:31.11232Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-05T22:07:31.113979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-05T22:07:31.158057Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:07:31.15818Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:07:31.158227Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:07:33.944435Z","caller":"traceutil/trace.go:171","msg":"trace[6949193] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"148.39317ms","start":"2026-02-05T22:07:33.795997Z","end":"2026-02-05T22:07:33.94439Z","steps":["trace[6949193] 'read index received' (duration: 23.083166ms)","trace[6949193] 'applied index is now lower than readState.Index' (duration: 125.308874ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:33.944774Z","caller":"traceutil/trace.go:171","msg":"trace[1255469661] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"148.690353ms","start":"2026-02-05T22:07:33.796061Z","end":"2026-02-05T22:07:33.944751Z","steps":["trace[1255469661] 'process raft request' (duration: 148.255408ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:33.944745Z","caller":"traceutil/trace.go:171","msg":"trace[955385088] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"149.013056ms","start":"2026-02-05T22:07:33.7957Z","end":"2026-02-05T22:07:33.944713Z","steps":["trace[955385088] 'process raft request' (duration: 129.538127ms)","trace[955385088] 'compare' (duration: 18.901793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:33.946091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.078447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-05T22:07:33.946228Z","caller":"traceutil/trace.go:171","msg":"trace[663317992] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4; }","duration":"150.239369ms","start":"2026-02-05T22:07:33.795973Z","end":"2026-02-05T22:07:33.946213Z","steps":["trace[663317992] 'agreement among raft nodes before linearized reading' (duration: 149.499841ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.116031Z","caller":"traceutil/trace.go:171","msg":"trace[1235404079] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"251.412639ms","start":"2026-02-05T22:07:33.864577Z","end":"2026-02-05T22:07:34.115989Z","steps":["trace[1235404079] 'process raft request' (duration: 247.64631ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.116574Z","caller":"traceutil/trace.go:171","msg":"trace[922590943] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"251.698042ms","start":"2026-02-05T22:07:33.86486Z","end":"2026-02-05T22:07:34.116558Z","steps":["trace[922590943] 'process raft request' (duration: 250.772652ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.116722Z","caller":"traceutil/trace.go:171","msg":"trace[127717666] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"251.938775ms","start":"2026-02-05T22:07:33.864771Z","end":"2026-02-05T22:07:34.11671Z","steps":["trace[127717666] 'process raft request' (duration: 250.789243ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.117011Z","caller":"traceutil/trace.go:171","msg":"trace[1937639250] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"252.134806ms","start":"2026-02-05T22:07:33.864864Z","end":"2026-02-05T22:07:34.116999Z","steps":["trace[1937639250] 'process raft request' (duration: 250.798143ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.118666Z","caller":"traceutil/trace.go:171","msg":"trace[114882001] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:7; }","duration":"174.116527ms","start":"2026-02-05T22:07:33.944536Z","end":"2026-02-05T22:07:34.118652Z","steps":["trace[114882001] 'read index received' (duration: 167.867574ms)","trace[114882001] 'applied index is now lower than readState.Index' (duration: 6.247913ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:34.118915Z","caller":"traceutil/trace.go:171","msg":"trace[1038914808] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"253.787864ms","start":"2026-02-05T22:07:33.864954Z","end":"2026-02-05T22:07:34.118742Z","steps":["trace[1038914808] 'process raft request' (duration: 250.760683ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.119293Z","caller":"traceutil/trace.go:171","msg":"trace[1339693945] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"254.291708ms","start":"2026-02-05T22:07:33.864991Z","end":"2026-02-05T22:07:34.119283Z","steps":["trace[1339693945] 'process raft request' (duration: 250.756162ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.120718Z","caller":"traceutil/trace.go:171","msg":"trace[1375205372] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"252.655211ms","start":"2026-02-05T22:07:33.868052Z","end":"2026-02-05T22:07:34.120707Z","steps":["trace[1375205372] 'process raft request' (duration: 250.54525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.120952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.388076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.121025Z","caller":"traceutil/trace.go:171","msg":"trace[811761481] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"323.493817ms","start":"2026-02-05T22:07:33.797517Z","end":"2026-02-05T22:07:34.12101Z","steps":["trace[811761481] 'agreement among raft nodes before linearized reading' (duration: 323.278785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.123268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:33.797511Z","time spent":"325.725689ms","remote":"127.0.0.1:41798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-05T22:07:34.121552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.335906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.123598Z","caller":"traceutil/trace.go:171","msg":"trace[573991848] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"287.410758ms","start":"2026-02-05T22:07:33.836175Z","end":"2026-02-05T22:07:34.123585Z","steps":["trace[573991848] 'agreement among raft nodes before linearized reading' (duration: 285.329617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.12161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.163845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.123789Z","caller":"traceutil/trace.go:171","msg":"trace[907793958] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"289.376158ms","start":"2026-02-05T22:07:33.8344Z","end":"2026-02-05T22:07:34.123776Z","steps":["trace[907793958] 'agreement among raft nodes before linearized reading' (duration: 287.194776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.121661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.110277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.123966Z","caller":"traceutil/trace.go:171","msg":"trace[1585411450] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:0; response_revision:11; }","duration":"263.425871ms","start":"2026-02-05T22:07:33.860529Z","end":"2026-02-05T22:07:34.123955Z","steps":["trace[1585411450] 'agreement among raft nodes before linearized reading' (duration: 261.100277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.121693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.509064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.124357Z","caller":"traceutil/trace.go:171","msg":"trace[1163812361] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"271.170881ms","start":"2026-02-05T22:07:33.85316Z","end":"2026-02-05T22:07:34.124331Z","steps":["trace[1163812361] 'agreement among raft nodes before linearized reading' (duration: 268.519214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.12178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.879377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.124447Z","caller":"traceutil/trace.go:171","msg":"trace[360953644] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"271.567024ms","start":"2026-02-05T22:07:33.852873Z","end":"2026-02-05T22:07:34.12444Z","steps":["trace[360953644] 'agreement among raft nodes before linearized reading' (duration: 268.598865ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.129476Z","caller":"traceutil/trace.go:171","msg":"trace[1233406645] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"175.167567ms","start":"2026-02-05T22:07:33.954299Z","end":"2026-02-05T22:07:34.129466Z","steps":["trace[1233406645] 'process raft request' (duration: 175.106697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.129793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.435489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-05T22:07:34.129861Z","caller":"traceutil/trace.go:171","msg":"trace[576007174] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"171.540681ms","start":"2026-02-05T22:07:33.95831Z","end":"2026-02-05T22:07:34.12985Z","steps":["trace[576007174] 'agreement among raft nodes before linearized reading' (duration: 171.361019ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.33013Z","caller":"traceutil/trace.go:171","msg":"trace[1086812123] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"125.272176ms","start":"2026-02-05T22:07:34.204826Z","end":"2026-02-05T22:07:34.330098Z","steps":["trace[1086812123] 'process raft request' (duration: 125.018593ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.330368Z","caller":"traceutil/trace.go:171","msg":"trace[749714976] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"127.62838ms","start":"2026-02-05T22:07:34.202728Z","end":"2026-02-05T22:07:34.330356Z","steps":["trace[749714976] 'process raft request' (duration: 89.32928ms)","trace[749714976] 'compare' (duration: 37.553823ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:34.330634Z","caller":"traceutil/trace.go:171","msg":"trace[1829263783] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"124.64466ms","start":"2026-02-05T22:07:34.205979Z","end":"2026-02-05T22:07:34.330624Z","steps":["trace[1829263783] 'process raft request' (duration: 123.928983ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.331235Z","caller":"traceutil/trace.go:171","msg":"trace[2144477914] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"112.269544ms","start":"2026-02-05T22:07:34.218909Z","end":"2026-02-05T22:07:34.331179Z","steps":["trace[2144477914] 'process raft request' (duration: 111.270294ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.331968Z","caller":"traceutil/trace.go:171","msg":"trace[2085199653] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"125.873512ms","start":"2026-02-05T22:07:34.206082Z","end":"2026-02-05T22:07:34.331955Z","steps":["trace[2085199653] 'process raft request' (duration: 123.989584ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.332388Z","caller":"traceutil/trace.go:171","msg":"trace[407095237] linearizableReadLoop","detail":"{readStateIndex:27; appliedIndex:23; }","duration":"121.77375ms","start":"2026-02-05T22:07:34.2106Z","end":"2026-02-05T22:07:34.332374Z","steps":["trace[407095237] 'read index received' (duration: 81.5378ms)","trace[407095237] 'applied index is now lower than readState.Index' (duration: 40.23456ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:34.332769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.613283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-05T22:07:34.33285Z","caller":"traceutil/trace.go:171","msg":"trace[623712369] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:25; }","duration":"139.781945ms","start":"2026-02-05T22:07:34.193056Z","end":"2026-02-05T22:07:34.332838Z","steps":["trace[623712369] 'agreement among raft nodes before linearized reading' (duration: 139.514222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.333185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.087614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.33354Z","caller":"traceutil/trace.go:171","msg":"trace[147472618] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:25; }","duration":"125.459227ms","start":"2026-02-05T22:07:34.20807Z","end":"2026-02-05T22:07:34.33353Z","steps":["trace[147472618] 'agreement among raft nodes before linearized reading' (duration: 125.077084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.333378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.246329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-05T22:07:34.334545Z","caller":"traceutil/trace.go:171","msg":"trace[1646588560] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:25; }","duration":"140.646234ms","start":"2026-02-05T22:07:34.193118Z","end":"2026-02-05T22:07:34.333764Z","steps":["trace[1646588560] 'agreement among raft nodes before linearized reading' (duration: 140.229449ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.332498Z","caller":"traceutil/trace.go:171","msg":"trace[1985749036] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"113.578557ms","start":"2026-02-05T22:07:34.218857Z","end":"2026-02-05T22:07:34.332436Z","steps":["trace[1985749036] 'process raft request' (duration: 111.286533ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.565822Z","caller":"traceutil/trace.go:171","msg":"trace[1377293385] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"141.057937ms","start":"2026-02-05T22:07:34.424739Z","end":"2026-02-05T22:07:34.565797Z","steps":["trace[1377293385] 'process raft request' (duration: 141.011976ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.566229Z","caller":"traceutil/trace.go:171","msg":"trace[1213575335] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"160.293313ms","start":"2026-02-05T22:07:34.405878Z","end":"2026-02-05T22:07:34.566171Z","steps":["trace[1213575335] 'process raft request' (duration: 159.825468ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.566252Z","caller":"traceutil/trace.go:171","msg":"trace[21297437] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:36; }","duration":"159.374904ms","start":"2026-02-05T22:07:34.406854Z","end":"2026-02-05T22:07:34.566228Z","steps":["trace[21297437] 'read index received' (duration: 13.052023ms)","trace[21297437] 'applied index is now lower than readState.Index' (duration: 146.319981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:34.566415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.530905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.567255Z","caller":"traceutil/trace.go:171","msg":"trace[1323741668] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:38; }","duration":"160.418683ms","start":"2026-02-05T22:07:34.406818Z","end":"2026-02-05T22:07:34.567236Z","steps":["trace[1323741668] 'agreement among raft nodes before linearized reading' (duration: 159.470704ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:34.566509Z","caller":"traceutil/trace.go:171","msg":"trace[1925080183] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"164.092921ms","start":"2026-02-05T22:07:34.402396Z","end":"2026-02-05T22:07:34.566489Z","steps":["trace[1925080183] 'process raft request' (duration: 66.565157ms)","trace[1925080183] 'compare' (duration: 96.632545ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:34.947184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.136125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-05T22:07:34.947513Z","caller":"traceutil/trace.go:171","msg":"trace[2124345897] linearizableReadLoop","detail":"{readStateIndex:52; appliedIndex:51; }","duration":"209.580145ms","start":"2026-02-05T22:07:34.73772Z","end":"2026-02-05T22:07:34.947301Z","steps":["trace[2124345897] 'read index received' (duration: 76.179717ms)","trace[2124345897] 'applied index is now lower than readState.Index' (duration: 133.398868ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:34.94746Z","caller":"traceutil/trace.go:171","msg":"trace[949506217] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"213.177601ms","start":"2026-02-05T22:07:34.734208Z","end":"2026-02-05T22:07:34.947386Z","steps":["trace[949506217] 'process raft request' (duration: 79.803753ms)","trace[949506217] 'compare' (duration: 131.880363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:34.948081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.005898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.948177Z","caller":"traceutil/trace.go:171","msg":"trace[298456276] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:48; }","duration":"209.16302ms","start":"2026-02-05T22:07:34.739001Z","end":"2026-02-05T22:07:34.948164Z","steps":["trace[298456276] 'agreement among raft nodes before linearized reading' (duration: 209.007259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:34.948361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.835917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:34.948604Z","caller":"traceutil/trace.go:171","msg":"trace[1448420341] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:48; }","duration":"214.327904ms","start":"2026-02-05T22:07:34.734072Z","end":"2026-02-05T22:07:34.9484Z","steps":["trace[1448420341] 'agreement among raft nodes before linearized reading' (duration: 213.752897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:35.474225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.339707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-05T22:07:35.474539Z","caller":"traceutil/trace.go:171","msg":"trace[499790265] linearizableReadLoop","detail":"{readStateIndex:56; appliedIndex:55; }","duration":"473.239895ms","start":"2026-02-05T22:07:35.001269Z","end":"2026-02-05T22:07:35.474509Z","steps":["trace[499790265] 'read index received' (duration: 65.553484ms)","trace[499790265] 'applied index is now lower than readState.Index' (duration: 407.61032ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:35.474794Z","caller":"traceutil/trace.go:171","msg":"trace[1973412101] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"473.900422ms","start":"2026-02-05T22:07:35.000872Z","end":"2026-02-05T22:07:35.474773Z","steps":["trace[1973412101] 'process raft request' (duration: 65.904608ms)","trace[1973412101] 'compare' (duration: 407.115325ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:35.475004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:35.000854Z","time spent":"474.033124ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T22:07:35.475319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.247235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:35.475411Z","caller":"traceutil/trace.go:171","msg":"trace[825977895] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:52; }","duration":"474.362677ms","start":"2026-02-05T22:07:35.001028Z","end":"2026-02-05T22:07:35.475391Z","steps":["trace[825977895] 'agreement among raft nodes before linearized reading' (duration: 473.793852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:35.475634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:35.001018Z","time spent":"474.507979ms","remote":"127.0.0.1:42014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"info","ts":"2026-02-05T22:07:35.648979Z","caller":"traceutil/trace.go:171","msg":"trace[235701367] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"645.42424ms","start":"2026-02-05T22:07:35.003525Z","end":"2026-02-05T22:07:35.648949Z","steps":["trace[235701367] 'process raft request' (duration: 605.398424ms)","trace[235701367] 'compare' (duration: 39.471351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:35.649548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:35.003517Z","time spent":"645.623732ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:07:35.649862Z","caller":"traceutil/trace.go:171","msg":"trace[1094796486] linearizableReadLoop","detail":"{readStateIndex:57; appliedIndex:56; }","duration":"175.101374ms","start":"2026-02-05T22:07:35.474719Z","end":"2026-02-05T22:07:35.649821Z","steps":["trace[1094796486] 'read index received' (duration: 134.32924ms)","trace[1094796486] 'applied index is now lower than readState.Index' (duration: 40.768284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:35.650088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.14478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:35.650665Z","caller":"traceutil/trace.go:171","msg":"trace[1500197254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:54; }","duration":"436.789486ms","start":"2026-02-05T22:07:35.21386Z","end":"2026-02-05T22:07:35.650649Z","steps":["trace[1500197254] 'agreement among raft nodes before linearized reading' (duration: 436.109129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:35.651053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:35.213831Z","time spent":"437.206089ms","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T22:07:35.651504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.521018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:35.651549Z","caller":"traceutil/trace.go:171","msg":"trace[249565993] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:54; }","duration":"172.614718ms","start":"2026-02-05T22:07:35.478921Z","end":"2026-02-05T22:07:35.651536Z","steps":["trace[249565993] 'agreement among raft nodes before linearized reading' (duration: 172.543917ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:35.652101Z","caller":"traceutil/trace.go:171","msg":"trace[1806980819] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"172.902471ms","start":"2026-02-05T22:07:35.47917Z","end":"2026-02-05T22:07:35.652072Z","steps":["trace[1806980819] 'process raft request' (duration: 170.687409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:36.128879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.233172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-05T22:07:36.132762Z","caller":"traceutil/trace.go:171","msg":"trace[559559771] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"346.599273ms","start":"2026-02-05T22:07:35.786132Z","end":"2026-02-05T22:07:36.132731Z","steps":["trace[559559771] 'process raft request' (duration: 137.415452ms)","trace[559559771] 'compare' (duration: 205.03467ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:36.135271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:35.786117Z","time spent":"349.093309ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:07:36.133548Z","caller":"traceutil/trace.go:171","msg":"trace[24395598] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"346.912538ms","start":"2026-02-05T22:07:35.786609Z","end":"2026-02-05T22:07:36.133522Z","steps":["trace[24395598] 'process raft request' (duration: 345.365242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:36.13562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:35.786574Z","time spent":"349.001889ms","remote":"127.0.0.1:42102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1089,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:07:36.133679Z","caller":"traceutil/trace.go:171","msg":"trace[2119275536] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"345.397962ms","start":"2026-02-05T22:07:35.788265Z","end":"2026-02-05T22:07:36.133663Z","steps":["trace[2119275536] 'process raft request' (duration: 343.842506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:36.136941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:07:35.788237Z","time spent":"348.675576ms","remote":"127.0.0.1:42012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:07:36.344832Z","caller":"traceutil/trace.go:171","msg":"trace[866973446] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"169.905472ms","start":"2026-02-05T22:07:36.17489Z","end":"2026-02-05T22:07:36.344795Z","steps":["trace[866973446] 'process raft request' (duration: 169.848842ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:36.345336Z","caller":"traceutil/trace.go:171","msg":"trace[635059930] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"205.281218ms","start":"2026-02-05T22:07:36.140037Z","end":"2026-02-05T22:07:36.345318Z","steps":["trace[635059930] 'process raft request' (duration: 158.192554ms)","trace[635059930] 'compare' (duration: 45.790481ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:36.345798Z","caller":"traceutil/trace.go:171","msg":"trace[1609530928] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"203.797215ms","start":"2026-02-05T22:07:36.141985Z","end":"2026-02-05T22:07:36.345783Z","steps":["trace[1609530928] 'process raft request' (duration: 202.268429ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:36.345854Z","caller":"traceutil/trace.go:171","msg":"trace[1134594964] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:69; }","duration":"204.709673ms","start":"2026-02-05T22:07:36.141133Z","end":"2026-02-05T22:07:36.345843Z","steps":["trace[1134594964] 'read index received' (duration: 30.039042ms)","trace[1134594964] 'applied index is now lower than readState.Index' (duration: 174.669441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:07:36.345983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.770975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:36.346671Z","caller":"traceutil/trace.go:171","msg":"trace[192017828] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:0; response_revision:67; }","duration":"205.525231ms","start":"2026-02-05T22:07:36.141108Z","end":"2026-02-05T22:07:36.346633Z","steps":["trace[192017828] 'agreement among raft nodes before linearized reading' (duration: 204.764904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:36.346922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.987381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:36.346961Z","caller":"traceutil/trace.go:171","msg":"trace[362546244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:67; }","duration":"133.073292ms","start":"2026-02-05T22:07:36.21388Z","end":"2026-02-05T22:07:36.346954Z","steps":["trace[362546244] 'agreement among raft nodes before linearized reading' (duration: 132.987141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:36.561085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.666875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-05T22:07:36.5615Z","caller":"traceutil/trace.go:171","msg":"trace[274703796] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:73; }","duration":"132.379714ms","start":"2026-02-05T22:07:36.429105Z","end":"2026-02-05T22:07:36.561485Z","steps":["trace[274703796] 'read index received' (duration: 19.250714ms)","trace[274703796] 'applied index is now lower than readState.Index' (duration: 113.11704ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:36.561659Z","caller":"traceutil/trace.go:171","msg":"trace[2121655319] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"207.237868ms","start":"2026-02-05T22:07:36.354352Z","end":"2026-02-05T22:07:36.56159Z","steps":["trace[2121655319] 'process raft request' (duration: 206.799013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:07:36.562092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.98088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-05T22:07:36.562126Z","caller":"traceutil/trace.go:171","msg":"trace[1070217936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:72; }","duration":"133.048691ms","start":"2026-02-05T22:07:36.429069Z","end":"2026-02-05T22:07:36.562118Z","steps":["trace[1070217936] 'agreement among raft nodes before linearized reading' (duration: 132.469936ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:36.56242Z","caller":"traceutil/trace.go:171","msg":"trace[1545796910] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"208.152317ms","start":"2026-02-05T22:07:36.354249Z","end":"2026-02-05T22:07:36.562401Z","steps":["trace[1545796910] 'process raft request' (duration: 94.090698ms)","trace[1545796910] 'compare' (duration: 112.554134ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:07:36.562994Z","caller":"traceutil/trace.go:171","msg":"trace[622195018] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"208.256079ms","start":"2026-02-05T22:07:36.354727Z","end":"2026-02-05T22:07:36.562983Z","steps":["trace[622195018] 'process raft request' (duration: 206.635923ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:36.563592Z","caller":"traceutil/trace.go:171","msg":"trace[1210195635] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"167.356506ms","start":"2026-02-05T22:07:36.396224Z","end":"2026-02-05T22:07:36.563581Z","steps":["trace[1210195635] 'process raft request' (duration: 165.195975ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:57.775482Z","caller":"traceutil/trace.go:171","msg":"trace[393899691] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"173.986394ms","start":"2026-02-05T22:07:57.600845Z","end":"2026-02-05T22:07:57.774831Z","steps":["trace[393899691] 'process raft request' (duration: 173.799332ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:07:57.776996Z","caller":"traceutil/trace.go:171","msg":"trace[1160334933] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"174.963033ms","start":"2026-02-05T22:07:57.602025Z","end":"2026-02-05T22:07:57.776988Z","steps":["trace[1160334933] 'process raft request' (duration: 174.310668ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:08:02.894591Z","caller":"traceutil/trace.go:171","msg":"trace[1436733443] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"118.351242ms","start":"2026-02-05T22:08:02.776212Z","end":"2026-02-05T22:08:02.894563Z","steps":["trace[1436733443] 'read index received' (duration: 118.140551ms)","trace[1436733443] 'applied index is now lower than readState.Index' (duration: 209.301µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:08:02.894734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.486684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:08:02.894769Z","caller":"traceutil/trace.go:171","msg":"trace[1745515825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"118.560075ms","start":"2026-02-05T22:08:02.776196Z","end":"2026-02-05T22:08:02.894756Z","steps":["trace[1745515825] 'agreement among raft nodes before linearized reading' (duration: 118.471494ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:08:02.894953Z","caller":"traceutil/trace.go:171","msg":"trace[96637072] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"132.770464ms","start":"2026-02-05T22:08:02.762152Z","end":"2026-02-05T22:08:02.894922Z","steps":["trace[96637072] 'process raft request' (duration: 132.212159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:03.769504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.064023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-02-05T22:08:03.769601Z","caller":"traceutil/trace.go:171","msg":"trace[1180611734] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:477; }","duration":"339.190495ms","start":"2026-02-05T22:08:03.430378Z","end":"2026-02-05T22:08:03.769568Z","steps":["trace[1180611734] 'range keys from in-memory index tree' (duration: 338.955643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:03.769755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:03.430359Z","time spent":"339.354757ms","remote":"127.0.0.1:41812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12687,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-05T22:08:08.682971Z","caller":"traceutil/trace.go:171","msg":"trace[785350930] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"166.746615ms","start":"2026-02-05T22:08:08.516199Z","end":"2026-02-05T22:08:08.682946Z","steps":["trace[785350930] 'process raft request' (duration: 166.669944ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:08:08.683277Z","caller":"traceutil/trace.go:171","msg":"trace[1240271182] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"203.564067ms","start":"2026-02-05T22:08:08.479707Z","end":"2026-02-05T22:08:08.683271Z","steps":["trace[1240271182] 'process raft request' (duration: 130.388647ms)","trace[1240271182] 'compare' (duration: 72.606924ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:08:09.342857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132553400176273,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:08:09.658448Z","caller":"traceutil/trace.go:171","msg":"trace[1256661302] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"882.574992ms","start":"2026-02-05T22:08:08.775838Z","end":"2026-02-05T22:08:09.658413Z","steps":["trace[1256661302] 'process raft request' (duration: 882.32122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.658647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:08.775817Z","time spent":"882.742373ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:08:09.768717Z","caller":"traceutil/trace.go:171","msg":"trace[111999437] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"962.42944ms","start":"2026-02-05T22:08:08.80627Z","end":"2026-02-05T22:08:09.7687Z","steps":["trace[111999437] 'process raft request' (duration: 960.16447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.76896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:08.80625Z","time spent":"962.608122ms","remote":"127.0.0.1:42116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:09.769558Z","caller":"traceutil/trace.go:171","msg":"trace[20059834] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"909.001219ms","start":"2026-02-05T22:08:08.860527Z","end":"2026-02-05T22:08:09.769528Z","steps":["trace[20059834] 'process raft request' (duration: 908.696717ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.769738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:08.860506Z","time spent":"909.12805ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:09.769909Z","caller":"traceutil/trace.go:171","msg":"trace[763975597] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"929.727075ms","start":"2026-02-05T22:08:08.840174Z","end":"2026-02-05T22:08:09.769901Z","steps":["trace[763975597] 'process raft request' (duration: 928.479524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.769972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:08.840152Z","time spent":"929.800986ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:09.770174Z","caller":"traceutil/trace.go:171","msg":"trace[885553021] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"877.03197ms","start":"2026-02-05T22:08:08.893136Z","end":"2026-02-05T22:08:09.770168Z","steps":["trace[885553021] 'process raft request' (duration: 876.366474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.770205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:08.893118Z","time spent":"877.07399ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:08:09.770582Z","caller":"traceutil/trace.go:171","msg":"trace[426616910] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:562; }","duration":"927.936289ms","start":"2026-02-05T22:08:08.842639Z","end":"2026-02-05T22:08:09.770575Z","steps":["trace[426616910] 'read index received' (duration: 816.513486ms)","trace[426616910] 'applied index is now lower than readState.Index' (duration: 111.421993ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:08:09.770687Z","caller":"traceutil/trace.go:171","msg":"trace[808571256] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"678.219908ms","start":"2026-02-05T22:08:09.092455Z","end":"2026-02-05T22:08:09.770675Z","steps":["trace[808571256] 'process raft request' (duration: 677.696922ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:08:09.770721Z","caller":"traceutil/trace.go:171","msg":"trace[196495658] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"276.664621ms","start":"2026-02-05T22:08:09.494051Z","end":"2026-02-05T22:08:09.770716Z","steps":["trace[196495658] 'process raft request' (duration: 276.502119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.770779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:09.092408Z","time spent":"678.349579ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T22:08:09.770837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.196652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:08:09.770864Z","caller":"traceutil/trace.go:171","msg":"trace[206568094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"928.225682ms","start":"2026-02-05T22:08:08.842633Z","end":"2026-02-05T22:08:09.770858Z","steps":["trace[206568094] 'agreement among raft nodes before linearized reading' (duration: 928.163872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.770886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:08.842604Z","time spent":"928.278682ms","remote":"127.0.0.1:41706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T22:08:09.770701Z","caller":"traceutil/trace.go:171","msg":"trace[1115397654] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"478.252257ms","start":"2026-02-05T22:08:09.292434Z","end":"2026-02-05T22:08:09.770686Z","steps":["trace[1115397654] 'process raft request' (duration: 478.076894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.77146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:09.292406Z","time spent":"478.998623ms","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:08:09.779657Z","caller":"traceutil/trace.go:171","msg":"trace[1408953319] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"114.620222ms","start":"2026-02-05T22:08:09.665026Z","end":"2026-02-05T22:08:09.779646Z","steps":["trace[1408953319] 'process raft request' (duration: 108.003652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.780059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.459366ms","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-05T22:08:09.780094Z","caller":"traceutil/trace.go:171","msg":"trace[73670161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"824.542177ms","start":"2026-02-05T22:08:08.955545Z","end":"2026-02-05T22:08:09.780087Z","steps":["trace[73670161] 'agreement among raft nodes before linearized reading' (duration: 823.997662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:09.780109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:08.955528Z","time spent":"824.577267ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-05T22:08:16.940047Z","caller":"traceutil/trace.go:171","msg":"trace[712430265] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"158.909982ms","start":"2026-02-05T22:08:16.781113Z","end":"2026-02-05T22:08:16.940023Z","steps":["trace[712430265] 'read index received' (duration: 158.64582ms)","trace[712430265] 'applied index is now lower than readState.Index' (duration: 262.592µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:08:16.940173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.035504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:08:16.940202Z","caller":"traceutil/trace.go:171","msg":"trace[710916981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"159.109534ms","start":"2026-02-05T22:08:16.781084Z","end":"2026-02-05T22:08:16.940193Z","steps":["trace[710916981] 'agreement among raft nodes before linearized reading' (duration: 159.033864ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:08:16.940451Z","caller":"traceutil/trace.go:171","msg":"trace[1315354955] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"479.679024ms","start":"2026-02-05T22:08:16.46076Z","end":"2026-02-05T22:08:16.940439Z","steps":["trace[1315354955] 'process raft request' (duration: 479.041139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:16.940535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:16.460738Z","time spent":"479.740455ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:08:18.477583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132553400176458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:08:18.955779Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06258231s","expected-duration":"1s"} {"level":"info","ts":"2026-02-05T22:08:18.956125Z","caller":"traceutil/trace.go:171","msg":"trace[1366074481] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"978.915807ms","start":"2026-02-05T22:08:17.977191Z","end":"2026-02-05T22:08:18.956107Z","steps":["trace[1366074481] 'read index received' (duration: 978.720735ms)","trace[1366074481] 'applied index is now lower than readState.Index' (duration: 194.522µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:08:18.956425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.443601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:08:18.956459Z","caller":"traceutil/trace.go:171","msg":"trace[2038776471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"178.491262ms","start":"2026-02-05T22:08:18.777958Z","end":"2026-02-05T22:08:18.956449Z","steps":["trace[2038776471] 'agreement among raft nodes before linearized reading' (duration: 178.424861ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:08:18.956611Z","caller":"traceutil/trace.go:171","msg":"trace[1163122333] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.063478317s","start":"2026-02-05T22:08:17.893125Z","end":"2026-02-05T22:08:18.956603Z","steps":["trace[1163122333] 'process raft request' (duration: 1.062856302s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:18.9566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.998402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-05T22:08:18.956692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:17.893112Z","time spent":"1.063516458s","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:18.956705Z","caller":"traceutil/trace.go:171","msg":"trace[1193685566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"974.144313ms","start":"2026-02-05T22:08:17.982535Z","end":"2026-02-05T22:08:18.956679Z","steps":["trace[1193685566] 'agreement among raft nodes before linearized reading' (duration: 973.811611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:18.956764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:17.982524Z","time spent":"974.228434ms","remote":"127.0.0.1:41954","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-05T22:08:18.956865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.675584ms","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-05T22:08:18.956889Z","caller":"traceutil/trace.go:171","msg":"trace[11362214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"979.739544ms","start":"2026-02-05T22:08:17.977142Z","end":"2026-02-05T22:08:18.956882Z","steps":["trace[11362214] 'agreement among raft nodes before linearized reading' (duration: 979.632052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:18.956909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:17.977123Z","time spent":"979.780944ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:08:18.957094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.183309ms","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-05T22:08:18.957118Z","caller":"traceutil/trace.go:171","msg":"trace[1635669905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:622; }","duration":"971.262489ms","start":"2026-02-05T22:08:17.985848Z","end":"2026-02-05T22:08:18.957111Z","steps":["trace[1635669905] 'agreement among raft nodes before linearized reading' (duration: 971.201358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:18.957137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:17.985829Z","time spent":"971.302339ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T22:08:18.957287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.855369ms","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-05T22:08:18.957305Z","caller":"traceutil/trace.go:171","msg":"trace[514458499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"949.91922ms","start":"2026-02-05T22:08:18.00738Z","end":"2026-02-05T22:08:18.957299Z","steps":["trace[514458499] 'agreement among raft nodes before linearized reading' (duration: 949.861729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:18.957321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:18.007367Z","time spent":"949.950611ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:08:19.463728Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132553400176461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:08:19.964858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132553400176461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:08:20.071724Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.108105923s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T22:08:20.527723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.702047ms","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-05T22:08:20.527861Z","caller":"traceutil/trace.go:171","msg":"trace[1518067513] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:622; }","duration":"523.854634ms","start":"2026-02-05T22:08:20.003987Z","end":"2026-02-05T22:08:20.527842Z","steps":["trace[1518067513] 'range keys from in-memory index tree' (duration: 523.681726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:20.528258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.270263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:08:20.528307Z","caller":"traceutil/trace.go:171","msg":"trace[1910343790] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.564782259s","start":"2026-02-05T22:08:18.963515Z","end":"2026-02-05T22:08:20.528297Z","steps":["trace[1910343790] 'process raft request' (duration: 1.108389391s)","trace[1910343790] 'compare' (duration: 456.137338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:08:20.528345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:18.963496Z","time spent":"1.56482738s","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:20.547997Z","caller":"traceutil/trace.go:171","msg":"trace[1020490984] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:641; }","duration":"1.584355326s","start":"2026-02-05T22:08:18.963623Z","end":"2026-02-05T22:08:20.547979Z","steps":["trace[1020490984] 'read index received' (duration: 1.108352583s)","trace[1020490984] 'applied index is now lower than readState.Index' (duration: 476.002003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:08:20.548134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58447422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:08:20.548161Z","caller":"traceutil/trace.go:171","msg":"trace[1380108547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.584558722s","start":"2026-02-05T22:08:18.963596Z","end":"2026-02-05T22:08:20.548154Z","steps":["trace[1380108547] 'agreement among raft nodes before linearized reading' (duration: 1.584454858s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:20.548185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:18.963533Z","time spent":"1.584646084s","remote":"127.0.0.1:41698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T22:08:20.548451Z","caller":"traceutil/trace.go:171","msg":"trace[1085788743] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.584684032s","start":"2026-02-05T22:08:18.963759Z","end":"2026-02-05T22:08:20.548443Z","steps":["trace[1085788743] 'process raft request' (duration: 1.584003496s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:20.548543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:18.963741Z","time spent":"1.584734713s","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:20.548619Z","caller":"traceutil/trace.go:171","msg":"trace[380957660] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.582506838s","start":"2026-02-05T22:08:18.966107Z","end":"2026-02-05T22:08:20.548614Z","steps":["trace[380957660] 'process raft request' (duration: 1.58177674s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:20.548643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:18.966097Z","time spent":"1.582532288s","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:20.548701Z","caller":"traceutil/trace.go:171","msg":"trace[1726741243] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.581648902s","start":"2026-02-05T22:08:18.967047Z","end":"2026-02-05T22:08:20.548696Z","steps":["trace[1726741243] 'process raft request' (duration: 1.580867192s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:20.548727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:18.967039Z","time spent":"1.581671902s","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:08:20.548848Z","caller":"traceutil/trace.go:171","msg":"trace[382119083] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"529.912148ms","start":"2026-02-05T22:08:20.018927Z","end":"2026-02-05T22:08:20.548839Z","steps":["trace[382119083] 'process raft request' (duration: 529.013973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:08:20.548884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:08:20.018896Z","time spent":"529.96872ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:08:41.956114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.681314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:08:41.956186Z","caller":"traceutil/trace.go:171","msg":"trace[1746026240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"179.808349ms","start":"2026-02-05T22:08:41.776362Z","end":"2026-02-05T22:08:41.95617Z","steps":["trace[1746026240] 'range keys from in-memory index tree' (duration: 179.612773ms)"],"step_count":1}