{"level":"warn","ts":"2026-04-28T06:42:24.600964Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:42:24.601057Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157: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.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-04-28T06:42:24.601135Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:42:24.601154Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-28T06:42:24.601176Z","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-04-28T06:42:24.601908Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-04-28T06:42:24.601978Z","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.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-04-28T06:42:24.614433Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.240707ms"} {"level":"info","ts":"2026-04-28T06:42:24.636524Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-04-28T06:42:24.636646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:42:24.63668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-04-28T06:42:24.636695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:42:24.636719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-04-28T06:42:24.636778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-04-28T06:42:24.686352Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:42:24.691003Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:42:24.709369Z","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-04-28T06:42:24.713714Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:42:24.714809Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:42:24.714826Z","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-04-28T06:42:24.714931Z","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-04-28T06:42:24.715017Z","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-04-28T06:42:24.719264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-04-28T06:42:24.719504Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-04-28T06:42:24.725729Z","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-04-28T06:42:24.725875Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-28T06:42:24.725909Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-04-28T06:42:24.727638Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:42:24.727671Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:42:24.737369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:42:24.737426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:42:24.737459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-04-28T06:42:24.737482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:42:24.737496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-28T06:42:24.737517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-04-28T06:42:24.737535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-04-28T06:42:24.739395Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:42:24.739395Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:24.739436Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:42:24.739619Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:42:24.739774Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:42:24.739912Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:42:24.742318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T06:42:24.743624Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:24.743713Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:24.743756Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:42:24.746825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"warn","ts":"2026-04-28T06:42:26.451562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.451751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-28T06:42:26.451864Z","caller":"traceutil/trace.go:171","msg":"trace[2029997828] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"216.48696ms","start":"2026-04-28T06:42:26.235346Z","end":"2026-04-28T06:42:26.451833Z","steps":["trace[2029997828] 'process raft request' (duration: 57.852221ms)","trace[2029997828] 'compare' (duration: 157.27237ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:26.451965Z","caller":"traceutil/trace.go:171","msg":"trace[2055189140] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:5; }","duration":"199.325914ms","start":"2026-04-28T06:42:26.2526Z","end":"2026-04-28T06:42:26.451926Z","steps":["trace[2055189140] 'read index received' (duration: 40.655914ms)","trace[2055189140] 'applied index is now lower than readState.Index' (duration: 158.66752ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:26.452118Z","caller":"traceutil/trace.go:171","msg":"trace[1321238129] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"197.648314ms","start":"2026-04-28T06:42:26.254448Z","end":"2026-04-28T06:42:26.452097Z","steps":["trace[1321238129] 'process raft request' (duration: 197.454713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:26.452201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.567555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:42:26.452269Z","caller":"traceutil/trace.go:171","msg":"trace[729535731] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:0; response_revision:6; }","duration":"199.677816ms","start":"2026-04-28T06:42:26.252574Z","end":"2026-04-28T06:42:26.452252Z","steps":["trace[729535731] 'agreement among raft nodes before linearized reading' (duration: 199.498195ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:26.452294Z","caller":"traceutil/trace.go:171","msg":"trace[881289740] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"197.716904ms","start":"2026-04-28T06:42:26.254572Z","end":"2026-04-28T06:42:26.452289Z","steps":["trace[881289740] 'process raft request' (duration: 197.456993ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:26.452224Z","caller":"traceutil/trace.go:171","msg":"trace[1944278410] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"197.787575ms","start":"2026-04-28T06:42:26.254416Z","end":"2026-04-28T06:42:26.452204Z","steps":["trace[1944278410] 'process raft request' (duration: 197.393052ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:26.452461Z","caller":"traceutil/trace.go:171","msg":"trace[432932889] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"197.26681ms","start":"2026-04-28T06:42:26.255189Z","end":"2026-04-28T06:42:26.452456Z","steps":["trace[432932889] 'process raft request' (duration: 196.884078ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:26.452638Z","caller":"traceutil/trace.go:171","msg":"trace[1879079713] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"197.398341ms","start":"2026-04-28T06:42:26.255233Z","end":"2026-04-28T06:42:26.452631Z","steps":["trace[1879079713] 'process raft request' (duration: 197.21329ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:26.452636Z","caller":"traceutil/trace.go:171","msg":"trace[247241481] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"197.33103ms","start":"2026-04-28T06:42:26.255184Z","end":"2026-04-28T06:42:26.452515Z","steps":["trace[247241481] 'process raft request' (duration: 197.157779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:26.452757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.151335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:42:26.452804Z","caller":"traceutil/trace.go:171","msg":"trace[180303624] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"161.218455ms","start":"2026-04-28T06:42:26.291576Z","end":"2026-04-28T06:42:26.452794Z","steps":["trace[180303624] 'agreement among raft nodes before linearized reading' (duration: 161.120924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:26.452813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.268222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:42:26.452857Z","caller":"traceutil/trace.go:171","msg":"trace[1620924660] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"146.338642ms","start":"2026-04-28T06:42:26.306512Z","end":"2026-04-28T06:42:26.452851Z","steps":["trace[1620924660] 'agreement among raft nodes before linearized reading' (duration: 146.276892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:26.452896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.491309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:42:26.452966Z","caller":"traceutil/trace.go:171","msg":"trace[1373674213] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"198.577419ms","start":"2026-04-28T06:42:26.254373Z","end":"2026-04-28T06:42:26.452951Z","steps":["trace[1373674213] 'agreement among raft nodes before linearized reading' (duration: 198.319337ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:26.453023Z","caller":"traceutil/trace.go:171","msg":"trace[1581534571] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"178.633283ms","start":"2026-04-28T06:42:26.274378Z","end":"2026-04-28T06:42:26.453011Z","steps":["trace[1581534571] 'process raft request' (duration: 178.225371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:26.453038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.859043ms","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-04-28T06:42:26.453079Z","caller":"traceutil/trace.go:171","msg":"trace[1472033916] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"197.908725ms","start":"2026-04-28T06:42:26.255159Z","end":"2026-04-28T06:42:26.453068Z","steps":["trace[1472033916] 'agreement among raft nodes before linearized reading' (duration: 197.854464ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:26.452894Z","caller":"traceutil/trace.go:171","msg":"trace[1678996203] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"189.617152ms","start":"2026-04-28T06:42:26.263255Z","end":"2026-04-28T06:42:26.452872Z","steps":["trace[1678996203] 'process raft request' (duration: 189.295081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:27.150262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.718509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T06:42:27.150418Z","caller":"traceutil/trace.go:171","msg":"trace[1280667259] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"607.164423ms","start":"2026-04-28T06:42:26.543228Z","end":"2026-04-28T06:42:27.150392Z","steps":["trace[1280667259] 'process raft request' (duration: 247.230403ms)","trace[1280667259] 'compare' (duration: 359.585688ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:27.150512Z","caller":"traceutil/trace.go:171","msg":"trace[1194304669] linearizableReadLoop","detail":"{readStateIndex:33; appliedIndex:25; }","duration":"541.230272ms","start":"2026-04-28T06:42:26.609273Z","end":"2026-04-28T06:42:27.150503Z","steps":["trace[1194304669] 'read index received' (duration: 181.166431ms)","trace[1194304669] 'applied index is now lower than readState.Index' (duration: 360.063211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:27.150502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.543207Z","time spent":"607.261533ms","remote":"127.0.0.1:35170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:27.150589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.310722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:42:27.150622Z","caller":"traceutil/trace.go:171","msg":"trace[77978136] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"541.379793ms","start":"2026-04-28T06:42:26.609235Z","end":"2026-04-28T06:42:27.150615Z","steps":["trace[77978136] 'agreement among raft nodes before linearized reading' (duration: 541.309533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:27.150651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.609218Z","time spent":"541.425333ms","remote":"127.0.0.1:35160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":26,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"info","ts":"2026-04-28T06:42:27.150772Z","caller":"traceutil/trace.go:171","msg":"trace[1029627434] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"604.928319ms","start":"2026-04-28T06:42:26.545837Z","end":"2026-04-28T06:42:27.150765Z","steps":["trace[1029627434] 'process raft request' (duration: 604.556597ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.150761Z","caller":"traceutil/trace.go:171","msg":"trace[485228036] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"607.475495ms","start":"2026-04-28T06:42:26.543232Z","end":"2026-04-28T06:42:27.150707Z","steps":["trace[485228036] 'process raft request' (duration: 607.100023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:27.150815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.545832Z","time spent":"604.966069ms","remote":"127.0.0.1:35568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:27.150874Z","caller":"traceutil/trace.go:171","msg":"trace[1591017737] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"604.814808ms","start":"2026-04-28T06:42:26.546054Z","end":"2026-04-28T06:42:27.150869Z","steps":["trace[1591017737] 'process raft request' (duration: 604.357745ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.150844Z","caller":"traceutil/trace.go:171","msg":"trace[1704529316] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"605.06031ms","start":"2026-04-28T06:42:26.545754Z","end":"2026-04-28T06:42:27.150814Z","steps":["trace[1704529316] 'process raft request' (duration: 604.612138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:27.150904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.546047Z","time spent":"604.846708ms","remote":"127.0.0.1:35568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:27.150903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.543207Z","time spent":"607.614575ms","remote":"127.0.0.1:35160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:27.150916Z","caller":"traceutil/trace.go:171","msg":"trace[1852116939] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"604.285585ms","start":"2026-04-28T06:42:26.54662Z","end":"2026-04-28T06:42:27.150905Z","steps":["trace[1852116939] 'process raft request' (duration: 603.849672ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.15095Z","caller":"traceutil/trace.go:171","msg":"trace[1972783175] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"604.185694ms","start":"2026-04-28T06:42:26.546758Z","end":"2026-04-28T06:42:27.150943Z","steps":["trace[1972783175] 'process raft request' (duration: 603.725881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:27.150974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.546748Z","time spent":"604.215264ms","remote":"127.0.0.1:35568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:27.150974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.546607Z","time spent":"604.337775ms","remote":"127.0.0.1:35568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:27.150976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.545742Z","time spent":"605.166811ms","remote":"127.0.0.1:35496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:27.151039Z","caller":"traceutil/trace.go:171","msg":"trace[1138623260] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"604.886688ms","start":"2026-04-28T06:42:26.546147Z","end":"2026-04-28T06:42:27.151034Z","steps":["trace[1138623260] 'process raft request' (duration: 604.301414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:27.151067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:26.546132Z","time spent":"604.924918ms","remote":"127.0.0.1:35568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:27.276715Z","caller":"traceutil/trace.go:171","msg":"trace[595348684] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"119.975664ms","start":"2026-04-28T06:42:27.156682Z","end":"2026-04-28T06:42:27.276658Z","steps":["trace[595348684] 'process raft request' (duration: 119.910924ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.276766Z","caller":"traceutil/trace.go:171","msg":"trace[752810399] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"120.8775ms","start":"2026-04-28T06:42:27.155861Z","end":"2026-04-28T06:42:27.276738Z","steps":["trace[752810399] 'process raft request' (duration: 95.966396ms)","trace[752810399] 'compare' (duration: 24.634403ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:27.27718Z","caller":"traceutil/trace.go:171","msg":"trace[310273849] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"120.154986ms","start":"2026-04-28T06:42:27.157016Z","end":"2026-04-28T06:42:27.277171Z","steps":["trace[310273849] 'process raft request' (duration: 119.916303ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.277256Z","caller":"traceutil/trace.go:171","msg":"trace[827648165] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"119.851144ms","start":"2026-04-28T06:42:27.157396Z","end":"2026-04-28T06:42:27.277248Z","steps":["trace[827648165] 'process raft request' (duration: 119.756114ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.27731Z","caller":"traceutil/trace.go:171","msg":"trace[1926594005] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"119.984175ms","start":"2026-04-28T06:42:27.157314Z","end":"2026-04-28T06:42:27.277299Z","steps":["trace[1926594005] 'process raft request' (duration: 119.769692ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.277378Z","caller":"traceutil/trace.go:171","msg":"trace[1695383737] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"120.008865ms","start":"2026-04-28T06:42:27.157358Z","end":"2026-04-28T06:42:27.277367Z","steps":["trace[1695383737] 'process raft request' (duration: 119.762843ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:27.27748Z","caller":"traceutil/trace.go:171","msg":"trace[1563623547] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"120.495067ms","start":"2026-04-28T06:42:27.156953Z","end":"2026-04-28T06:42:27.277448Z","steps":["trace[1563623547] 'process raft request' (duration: 119.843323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:27.277636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.082902ms","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-04-28T06:42:27.277685Z","caller":"traceutil/trace.go:171","msg":"trace[1705994354] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:38; }","duration":"121.153303ms","start":"2026-04-28T06:42:27.156517Z","end":"2026-04-28T06:42:27.27767Z","steps":["trace[1705994354] 'agreement among raft nodes before linearized reading' (duration: 120.958732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:28.501345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.408369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-28T06:42:28.501519Z","caller":"traceutil/trace.go:171","msg":"trace[362455398] linearizableReadLoop","detail":"{readStateIndex:113; appliedIndex:112; }","duration":"156.416695ms","start":"2026-04-28T06:42:28.345087Z","end":"2026-04-28T06:42:28.501503Z","steps":["trace[362455398] 'read index received' (duration: 32.92µs)","trace[362455398] 'applied index is now lower than readState.Index' (duration: 156.382115ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:28.501638Z","caller":"traceutil/trace.go:171","msg":"trace[1828442805] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"530.295322ms","start":"2026-04-28T06:42:27.971204Z","end":"2026-04-28T06:42:28.501499Z","steps":["trace[1828442805] 'process raft request' (duration: 108.66866ms)","trace[1828442805] 'compare' (duration: 421.299989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:28.501672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.575936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T06:42:28.501706Z","caller":"traceutil/trace.go:171","msg":"trace[1422882069] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:109; }","duration":"156.659686ms","start":"2026-04-28T06:42:28.345035Z","end":"2026-04-28T06:42:28.501694Z","steps":["trace[1422882069] 'agreement among raft nodes before linearized reading' (duration: 156.533305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:28.501781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:27.971195Z","time spent":"530.474403ms","remote":"127.0.0.1:35392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1368,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:28.513581Z","caller":"traceutil/trace.go:171","msg":"trace[1456852077] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"160.837352ms","start":"2026-04-28T06:42:28.352723Z","end":"2026-04-28T06:42:28.51356Z","steps":["trace[1456852077] 'process raft request' (duration: 160.762722ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:28.518808Z","caller":"traceutil/trace.go:171","msg":"trace[1837525035] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"119.862959ms","start":"2026-04-28T06:42:28.398922Z","end":"2026-04-28T06:42:28.518785Z","steps":["trace[1837525035] 'process raft request' (duration: 119.711938ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:30.359498Z","caller":"traceutil/trace.go:171","msg":"trace[5144053] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"136.929356ms","start":"2026-04-28T06:42:30.222536Z","end":"2026-04-28T06:42:30.359465Z","steps":["trace[5144053] 'process raft request' (duration: 89.195915ms)","trace[5144053] 'compare' (duration: 47.58362ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:32.373472Z","caller":"traceutil/trace.go:171","msg":"trace[673286496] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"389.239424ms","start":"2026-04-28T06:42:31.984206Z","end":"2026-04-28T06:42:32.373445Z","steps":["trace[673286496] 'process raft request' (duration: 389.048593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:32.373699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:31.98419Z","time spent":"389.378145ms","remote":"127.0.0.1:35228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:42:32.987111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.193252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:42:32.987194Z","caller":"traceutil/trace.go:171","msg":"trace[75863084] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"608.73405ms","start":"2026-04-28T06:42:32.378446Z","end":"2026-04-28T06:42:32.98718Z","steps":["trace[75863084] 'read index received' (duration: 77.350747ms)","trace[75863084] 'applied index is now lower than readState.Index' (duration: 531.382443ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:32.987274Z","caller":"traceutil/trace.go:171","msg":"trace[1946897262] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"962.845282ms","start":"2026-04-28T06:42:32.02439Z","end":"2026-04-28T06:42:32.987236Z","steps":["trace[1946897262] 'process raft request' (duration: 431.476139ms)","trace[1946897262] 'compare' (duration: 531.044721ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:32.987341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.893301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-04-28T06:42:32.987377Z","caller":"traceutil/trace.go:171","msg":"trace[260890384] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:311; }","duration":"609.910137ms","start":"2026-04-28T06:42:32.377455Z","end":"2026-04-28T06:42:32.987366Z","steps":["trace[260890384] 'agreement among raft nodes before linearized reading' (duration: 609.799747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:32.987425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:32.377429Z","time spent":"609.988538ms","remote":"127.0.0.1:35228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2940,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-28T06:42:32.987412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.153864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:42:32.987451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:32.024369Z","time spent":"963.004563ms","remote":"127.0.0.1:35242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:32.98749Z","caller":"traceutil/trace.go:171","msg":"trace[657309533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:311; }","duration":"184.280984ms","start":"2026-04-28T06:42:32.80319Z","end":"2026-04-28T06:42:32.987471Z","steps":["trace[657309533] 'agreement among raft nodes before linearized reading' (duration: 184.136303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:33.4938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123786809019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:42:35.491421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741683492s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:42:35.491519Z","caller":"traceutil/trace.go:171","msg":"trace[432541166] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"2.499931566s","start":"2026-04-28T06:42:32.991568Z","end":"2026-04-28T06:42:35.4915Z","steps":["trace[432541166] 'process raft request' (duration: 758.112553ms)","trace[432541166] 'compare' (duration: 1.74141422s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:35.49157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:32.991541Z","time spent":"2.500009866s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:35.59884Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.849157663s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:42:36.694046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094917709s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:42:36.694256Z","caller":"traceutil/trace.go:171","msg":"trace[61969636] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"1.198095974s","start":"2026-04-28T06:42:35.496143Z","end":"2026-04-28T06:42:36.694239Z","steps":["trace[61969636] 'process raft request' (duration: 1.198016164s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:36.694246Z","caller":"traceutil/trace.go:171","msg":"trace[214013797] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"3.701900081s","start":"2026-04-28T06:42:32.992312Z","end":"2026-04-28T06:42:36.694213Z","steps":["trace[214013797] 'process raft request' (duration: 2.606723501s)","trace[214013797] 'compare' (duration: 1.094649527s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:36.694318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:35.496124Z","time spent":"1.198167954s","remote":"127.0.0.1:35134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:36.694393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:32.992298Z","time spent":"3.702017363s","remote":"127.0.0.1:35228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:42:36.694492Z","caller":"traceutil/trace.go:171","msg":"trace[324362002] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:328; }","duration":"3.701075607s","start":"2026-04-28T06:42:32.993405Z","end":"2026-04-28T06:42:36.694481Z","steps":["trace[324362002] 'read index received' (duration: 756.290701ms)","trace[324362002] 'applied index is now lower than readState.Index' (duration: 2.944783756s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:36.694685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.701275178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-28T06:42:36.694714Z","caller":"traceutil/trace.go:171","msg":"trace[360434036] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:314; }","duration":"3.701332718s","start":"2026-04-28T06:42:32.993372Z","end":"2026-04-28T06:42:36.694705Z","steps":["trace[360434036] 'agreement among raft nodes before linearized reading' (duration: 3.701216457s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:36.694715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.24950735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T06:42:36.694741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:32.993359Z","time spent":"3.701376279s","remote":"127.0.0.1:35170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-28T06:42:36.694755Z","caller":"traceutil/trace.go:171","msg":"trace[146808576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:314; }","duration":"3.24958067s","start":"2026-04-28T06:42:33.445163Z","end":"2026-04-28T06:42:36.694744Z","steps":["trace[146808576] 'agreement among raft nodes before linearized reading' (duration: 3.24950983s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:36.694786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:33.445144Z","time spent":"3.24963538s","remote":"127.0.0.1:35296","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-04-28T06:42:36.694926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199332371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:42:36.694956Z","caller":"traceutil/trace.go:171","msg":"trace[1836153265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:314; }","duration":"1.199404132s","start":"2026-04-28T06:42:35.495541Z","end":"2026-04-28T06:42:36.694945Z","steps":["trace[1836153265] 'agreement among raft nodes before linearized reading' (duration: 1.199356312s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:36.694993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.094773947s","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-04-28T06:42:36.694981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:35.495518Z","time spent":"1.199457332s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T06:42:36.695023Z","caller":"traceutil/trace.go:171","msg":"trace[143432072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:314; }","duration":"3.094846219s","start":"2026-04-28T06:42:33.600168Z","end":"2026-04-28T06:42:36.695014Z","steps":["trace[143432072] 'agreement among raft nodes before linearized reading' (duration: 3.094791238s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:36.695049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:33.600146Z","time spent":"3.094897789s","remote":"127.0.0.1:35296","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":"warn","ts":"2026-04-28T06:42:36.695126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.086128936s","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-04-28T06:42:36.695152Z","caller":"traceutil/trace.go:171","msg":"trace[518732967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:314; }","duration":"3.086200856s","start":"2026-04-28T06:42:33.608944Z","end":"2026-04-28T06:42:36.695145Z","steps":["trace[518732967] 'agreement among raft nodes before linearized reading' (duration: 3.086160546s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:36.695178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:33.608914Z","time spent":"3.086255756s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:42:37.039626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.745597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:42:37.03974Z","caller":"traceutil/trace.go:171","msg":"trace[1216274599] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"134.90032ms","start":"2026-04-28T06:42:36.90482Z","end":"2026-04-28T06:42:37.039721Z","steps":["trace[1216274599] 'read index received' (duration: 6.868792ms)","trace[1216274599] 'applied index is now lower than readState.Index' (duration: 128.030498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:37.039875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.044602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-04-28T06:42:37.039846Z","caller":"traceutil/trace.go:171","msg":"trace[390740432] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"164.934728ms","start":"2026-04-28T06:42:36.874871Z","end":"2026-04-28T06:42:37.039806Z","steps":["trace[390740432] 'process raft request' (duration: 36.911699ms)","trace[390740432] 'compare' (duration: 127.615326ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:37.039915Z","caller":"traceutil/trace.go:171","msg":"trace[860645335] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:333; }","duration":"135.098372ms","start":"2026-04-28T06:42:36.904806Z","end":"2026-04-28T06:42:37.039904Z","steps":["trace[860645335] 'agreement among raft nodes before linearized reading' (duration: 134.965041ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:38.874915Z","caller":"traceutil/trace.go:171","msg":"trace[89021454] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"108.237528ms","start":"2026-04-28T06:42:38.766658Z","end":"2026-04-28T06:42:38.874895Z","steps":["trace[89021454] 'process raft request' (duration: 57.795131ms)","trace[89021454] 'compare' (duration: 50.306356ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:38.875276Z","caller":"traceutil/trace.go:171","msg":"trace[93495665] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"108.479919ms","start":"2026-04-28T06:42:38.76679Z","end":"2026-04-28T06:42:38.87527Z","steps":["trace[93495665] 'process raft request' (duration: 108.079426ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:38.875629Z","caller":"traceutil/trace.go:171","msg":"trace[7281230] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"107.379171ms","start":"2026-04-28T06:42:38.768225Z","end":"2026-04-28T06:42:38.875604Z","steps":["trace[7281230] 'process raft request' (duration: 106.836228ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:39.758693Z","caller":"traceutil/trace.go:171","msg":"trace[237386288] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:392; }","duration":"106.423014ms","start":"2026-04-28T06:42:39.652248Z","end":"2026-04-28T06:42:39.758671Z","steps":["trace[237386288] 'read index received' (duration: 8.037897ms)","trace[237386288] 'applied index is now lower than readState.Index' (duration: 98.384217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:39.758842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.588795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-28T06:42:39.75871Z","caller":"traceutil/trace.go:171","msg":"trace[1112263253] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"130.123762ms","start":"2026-04-28T06:42:39.628564Z","end":"2026-04-28T06:42:39.758688Z","steps":["trace[1112263253] 'process raft request' (duration: 31.735865ms)","trace[1112263253] 'compare' (duration: 98.203876ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:39.75889Z","caller":"traceutil/trace.go:171","msg":"trace[1168164199] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:376; }","duration":"106.666985ms","start":"2026-04-28T06:42:39.652202Z","end":"2026-04-28T06:42:39.758869Z","steps":["trace[1168164199] 'agreement among raft nodes before linearized reading' (duration: 106.558314ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:39.758759Z","caller":"traceutil/trace.go:171","msg":"trace[446028772] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"123.718165ms","start":"2026-04-28T06:42:39.634994Z","end":"2026-04-28T06:42:39.758712Z","steps":["trace[446028772] 'process raft request' (duration: 123.633815ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:40.165706Z","caller":"traceutil/trace.go:171","msg":"trace[470136946] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"168.140591ms","start":"2026-04-28T06:42:39.997541Z","end":"2026-04-28T06:42:40.165682Z","steps":["trace[470136946] 'process raft request' (duration: 77.233681ms)","trace[470136946] 'compare' (duration: 90.764309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:49.486266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:49.146735Z","time spent":"339.526995ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T06:42:53.92965Z","caller":"traceutil/trace.go:171","msg":"trace[653451015] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"458.554561ms","start":"2026-04-28T06:42:53.471018Z","end":"2026-04-28T06:42:53.929573Z","steps":["trace[653451015] 'process raft request' (duration: 458.280918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:53.929871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.470996Z","time spent":"458.77369ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:53.938935Z","caller":"traceutil/trace.go:171","msg":"trace[689947989] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"465.655884ms","start":"2026-04-28T06:42:53.473251Z","end":"2026-04-28T06:42:53.938907Z","steps":["trace[689947989] 'process raft request' (duration: 462.643985ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:53.938988Z","caller":"traceutil/trace.go:171","msg":"trace[486906054] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"461.723396ms","start":"2026-04-28T06:42:53.477233Z","end":"2026-04-28T06:42:53.938956Z","steps":["trace[486906054] 'process raft request' (duration: 461.610842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:53.939105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.473234Z","time spent":"465.775639ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:53.939211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.477211Z","time spent":"461.851152ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:53.93926Z","caller":"traceutil/trace.go:171","msg":"trace[899427922] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"453.976924ms","start":"2026-04-28T06:42:53.485257Z","end":"2026-04-28T06:42:53.939234Z","steps":["trace[899427922] 'process raft request' (duration: 453.765235ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:53.939313Z","caller":"traceutil/trace.go:171","msg":"trace[947716488] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"458.855583ms","start":"2026-04-28T06:42:53.48045Z","end":"2026-04-28T06:42:53.939306Z","steps":["trace[947716488] 'process raft request' (duration: 458.422565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:53.939404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.480426Z","time spent":"458.921386ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:53.939409Z","caller":"traceutil/trace.go:171","msg":"trace[912337838] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"454.093449ms","start":"2026-04-28T06:42:53.4853Z","end":"2026-04-28T06:42:53.939393Z","steps":["trace[912337838] 'process raft request' (duration: 453.794966ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:53.939489Z","caller":"traceutil/trace.go:171","msg":"trace[1987265137] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"457.779637ms","start":"2026-04-28T06:42:53.481703Z","end":"2026-04-28T06:42:53.939483Z","steps":["trace[1987265137] 'process raft request' (duration: 457.198192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:53.939462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.485291Z","time spent":"454.146852ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:53.939523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.481685Z","time spent":"457.824539ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:53.939556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.48525Z","time spent":"454.043817ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:53.939598Z","caller":"traceutil/trace.go:171","msg":"trace[928562024] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"451.444416ms","start":"2026-04-28T06:42:53.488148Z","end":"2026-04-28T06:42:53.939592Z","steps":["trace[928562024] 'process raft request' (duration: 451.020638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:53.939625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.488138Z","time spent":"451.472727ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:53.939249Z","caller":"traceutil/trace.go:171","msg":"trace[1068701079] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"454.037127ms","start":"2026-04-28T06:42:53.48519Z","end":"2026-04-28T06:42:53.939227Z","steps":["trace[1068701079] 'process raft request' (duration: 453.796157ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:53.939797Z","caller":"traceutil/trace.go:171","msg":"trace[1869169413] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"292.963078ms","start":"2026-04-28T06:42:53.646815Z","end":"2026-04-28T06:42:53.939778Z","steps":["trace[1869169413] 'process raft request' (duration: 292.682216ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:42:53.939827Z","caller":"traceutil/trace.go:171","msg":"trace[856699972] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"425.681835ms","start":"2026-04-28T06:42:53.514132Z","end":"2026-04-28T06:42:53.939814Z","steps":["trace[856699972] 'process raft request' (duration: 425.219245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:53.939855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.485179Z","time spent":"454.651283ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:53.939865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.514114Z","time spent":"425.734417ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:53.939875Z","caller":"traceutil/trace.go:171","msg":"trace[1332339367] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:493; }","duration":"164.455742ms","start":"2026-04-28T06:42:53.775399Z","end":"2026-04-28T06:42:53.939855Z","steps":["trace[1332339367] 'read index received' (duration: 153.920552ms)","trace[1332339367] 'applied index is now lower than readState.Index' (duration: 10.53405ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:42:53.93979Z","caller":"traceutil/trace.go:171","msg":"trace[1982194610] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"438.312564ms","start":"2026-04-28T06:42:53.50147Z","end":"2026-04-28T06:42:53.939782Z","steps":["trace[1982194610] 'process raft request' (duration: 437.757161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:53.939926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:53.501453Z","time spent":"438.46305ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:42:53.940001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.591948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:42:53.940069Z","caller":"traceutil/trace.go:171","msg":"trace[258394119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"164.698003ms","start":"2026-04-28T06:42:53.775361Z","end":"2026-04-28T06:42:53.940059Z","steps":["trace[258394119] 'agreement among raft nodes before linearized reading' (duration: 164.545547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:54.644588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.480302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:42:54.644837Z","caller":"traceutil/trace.go:171","msg":"trace[179600059] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"458.535184ms","start":"2026-04-28T06:42:54.186267Z","end":"2026-04-28T06:42:54.644802Z","steps":["trace[179600059] 'process raft request' (duration: 194.77582ms)","trace[179600059] 'compare' (duration: 263.268344ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:42:54.64499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:54.186236Z","time spent":"458.6803ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:42:54.645387Z","caller":"traceutil/trace.go:171","msg":"trace[151736430] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"453.70217ms","start":"2026-04-28T06:42:54.19167Z","end":"2026-04-28T06:42:54.645372Z","steps":["trace[151736430] 'process raft request' (duration: 453.098735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:42:54.645462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:54.191645Z","time spent":"453.777753ms","remote":"127.0.0.1:35102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:00.515296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123786809558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:43:01.023126Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123786809558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:43:01.285427Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.491403798s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:43:01.286244Z","caller":"traceutil/trace.go:171","msg":"trace[1686249986] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"1.271867192s","start":"2026-04-28T06:43:00.014358Z","end":"2026-04-28T06:43:01.286226Z","steps":["trace[1686249986] 'read index received' (duration: 1.271219416s)","trace[1686249986] 'applied index is now lower than readState.Index' (duration: 647.056µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:01.286332Z","caller":"traceutil/trace.go:171","msg":"trace[1416930036] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.493434348s","start":"2026-04-28T06:42:59.792864Z","end":"2026-04-28T06:43:01.286299Z","steps":["trace[1416930036] 'process raft request' (duration: 1.492952488s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.286404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272037709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2zmbv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T06:43:01.286427Z","caller":"traceutil/trace.go:171","msg":"trace[1277521933] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2zmbv; range_end:; response_count:1; response_revision:577; }","duration":"1.272138213s","start":"2026-04-28T06:43:00.014282Z","end":"2026-04-28T06:43:01.28642Z","steps":["trace[1277521933] 'agreement among raft nodes before linearized reading' (duration: 1.272034819s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.286447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.014261Z","time spent":"1.272181815s","remote":"127.0.0.1:35228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-2zmbv\" "} {"level":"warn","ts":"2026-04-28T06:43:01.286592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.92209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:43:01.286504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:42:59.792574Z","time spent":"1.493847376s","remote":"127.0.0.1:35152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036253,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:01.286665Z","caller":"traceutil/trace.go:171","msg":"trace[1031048724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"511.034274ms","start":"2026-04-28T06:43:00.77561Z","end":"2026-04-28T06:43:01.286644Z","steps":["trace[1031048724] 'agreement among raft nodes before linearized reading' (duration: 510.898619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.286675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.113818ms","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-04-28T06:43:01.286743Z","caller":"traceutil/trace.go:171","msg":"trace[313639980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"283.194691ms","start":"2026-04-28T06:43:01.003534Z","end":"2026-04-28T06:43:01.286728Z","steps":["trace[313639980] 'agreement among raft nodes before linearized reading' (duration: 283.011534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.286703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.775594Z","time spent":"511.103176ms","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:43:01.286585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.574943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T06:43:01.28687Z","caller":"traceutil/trace.go:171","msg":"trace[487007726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"276.870565ms","start":"2026-04-28T06:43:01.009985Z","end":"2026-04-28T06:43:01.286855Z","steps":["trace[487007726] 'agreement among raft nodes before linearized reading' (duration: 276.572933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.286544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.81759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-28T06:43:01.286944Z","caller":"traceutil/trace.go:171","msg":"trace[58122660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"278.256928ms","start":"2026-04-28T06:43:01.008678Z","end":"2026-04-28T06:43:01.286935Z","steps":["trace[58122660] 'agreement among raft nodes before linearized reading' (duration: 277.816201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.287532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.952666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:43:01.287678Z","caller":"traceutil/trace.go:171","msg":"trace[2051658906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"321.114392ms","start":"2026-04-28T06:43:00.966508Z","end":"2026-04-28T06:43:01.287622Z","steps":["trace[2051658906] 'agreement among raft nodes before linearized reading' (duration: 320.085932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.287768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:00.966488Z","time spent":"321.265578ms","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-28T06:43:01.901888Z","caller":"traceutil/trace.go:171","msg":"trace[1131474471] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"607.134503ms","start":"2026-04-28T06:43:01.294738Z","end":"2026-04-28T06:43:01.901872Z","steps":["trace[1131474471] 'process raft request' (duration: 607.029039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:01.902009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.29472Z","time spent":"607.229706ms","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:02.60677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123786809562,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:43:02.704075Z","caller":"traceutil/trace.go:171","msg":"trace[1327984988] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.386545759s","start":"2026-04-28T06:43:01.31751Z","end":"2026-04-28T06:43:02.704056Z","steps":["trace[1327984988] 'process raft request' (duration: 1.386371292s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.704081Z","caller":"traceutil/trace.go:171","msg":"trace[2077226127] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.400406629s","start":"2026-04-28T06:43:01.303646Z","end":"2026-04-28T06:43:02.704052Z","steps":["trace[2077226127] 'process raft request' (duration: 1.400147609s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:02.704145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.726279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:43:02.704166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.317484Z","time spent":"1.386631362s","remote":"127.0.0.1:35228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:02.704178Z","caller":"traceutil/trace.go:171","msg":"trace[1642887219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"597.802111ms","start":"2026-04-28T06:43:02.106367Z","end":"2026-04-28T06:43:02.704169Z","steps":["trace[1642887219] 'agreement among raft nodes before linearized reading' (duration: 597.730918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:02.704193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.30364Z","time spent":"1.400492902s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:02.704202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:02.106347Z","time spent":"597.849983ms","remote":"127.0.0.1:35046","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-04-28T06:43:02.704221Z","caller":"traceutil/trace.go:171","msg":"trace[842930555] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.400515932s","start":"2026-04-28T06:43:01.303682Z","end":"2026-04-28T06:43:02.704198Z","steps":["trace[842930555] 'process raft request' (duration: 1.400161029s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.704057Z","caller":"traceutil/trace.go:171","msg":"trace[1421650083] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:604; }","duration":"597.612684ms","start":"2026-04-28T06:43:02.10642Z","end":"2026-04-28T06:43:02.704033Z","steps":["trace[1421650083] 'read index received' (duration: 590.045833ms)","trace[1421650083] 'applied index is now lower than readState.Index' (duration: 7.566081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:02.704353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.303677Z","time spent":"1.400615546s","remote":"127.0.0.1:35296","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-04-28T06:43:02.704351Z","caller":"traceutil/trace.go:171","msg":"trace[812296904] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.267688089s","start":"2026-04-28T06:43:01.43663Z","end":"2026-04-28T06:43:02.704319Z","steps":["trace[812296904] 'process raft request' (duration: 1.267319215s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.704359Z","caller":"traceutil/trace.go:171","msg":"trace[1073583733] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.400780432s","start":"2026-04-28T06:43:01.303547Z","end":"2026-04-28T06:43:02.704327Z","steps":["trace[1073583733] 'process raft request' (duration: 1.392954711s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.704398Z","caller":"traceutil/trace.go:171","msg":"trace[365842985] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.342262668s","start":"2026-04-28T06:43:01.362127Z","end":"2026-04-28T06:43:02.70439Z","steps":["trace[365842985] 'process raft request' (duration: 1.341789379s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:02.704427Z","caller":"traceutil/trace.go:171","msg":"trace[970891759] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"806.407285ms","start":"2026-04-28T06:43:01.89801Z","end":"2026-04-28T06:43:02.704418Z","steps":["trace[970891759] 'process raft request' (duration: 805.985448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:02.704449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.3621Z","time spent":"1.34232195s","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:43:02.704481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.897986Z","time spent":"806.465387ms","remote":"127.0.0.1:35220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:02.704467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.436605Z","time spent":"1.267802313s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:02.704492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:01.303529Z","time spent":"1.400893557s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:04.226034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:02.972486Z","time spent":"1.253541857s","remote":"127.0.0.1:52604","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-28T06:43:04.273873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123786809573,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:43:04.774193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123786809573,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:43:04.964145Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.831615713s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T06:43:04.969623Z","caller":"traceutil/trace.go:171","msg":"trace[677784532] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.838590915s","start":"2026-04-28T06:43:03.130966Z","end":"2026-04-28T06:43:04.969557Z","steps":["trace[677784532] 'process raft request' (duration: 1.833850928s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:04.969788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:03.130935Z","time spent":"1.838776053s","remote":"127.0.0.1:35152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:04.976316Z","caller":"traceutil/trace.go:171","msg":"trace[175352729] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"1.202753076s","start":"2026-04-28T06:43:03.773488Z","end":"2026-04-28T06:43:04.976242Z","steps":["trace[175352729] 'read index received' (duration: 1.19085856s)","trace[175352729] 'applied index is now lower than readState.Index' (duration: 11.893226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:04.976411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202928752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:04.976448Z","caller":"traceutil/trace.go:171","msg":"trace[1820860493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.203006135s","start":"2026-04-28T06:43:03.773432Z","end":"2026-04-28T06:43:04.976439Z","steps":["trace[1820860493] 'agreement among raft nodes before linearized reading' (duration: 1.202943483s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:04.976475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:03.773413Z","time spent":"1.203057296s","remote":"127.0.0.1:35046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:43:04.97663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066732939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-28T06:43:04.976639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.864307ms","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-04-28T06:43:04.976691Z","caller":"traceutil/trace.go:171","msg":"trace[524758502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"1.066837333s","start":"2026-04-28T06:43:03.90984Z","end":"2026-04-28T06:43:04.976678Z","steps":["trace[524758502] 'agreement among raft nodes before linearized reading' (duration: 1.066616745s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:04.976712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.23291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T06:43:04.976705Z","caller":"traceutil/trace.go:171","msg":"trace[1201324020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"267.973261ms","start":"2026-04-28T06:43:04.708716Z","end":"2026-04-28T06:43:04.976689Z","steps":["trace[1201324020] 'agreement among raft nodes before linearized reading' (duration: 267.778303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:04.976734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:03.909823Z","time spent":"1.066901435s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-28T06:43:04.976742Z","caller":"traceutil/trace.go:171","msg":"trace[1053410585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"268.262871ms","start":"2026-04-28T06:43:04.708472Z","end":"2026-04-28T06:43:04.976735Z","steps":["trace[1053410585] 'agreement among raft nodes before linearized reading' (duration: 268.179268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:04.976636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.104706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:43:04.97682Z","caller":"traceutil/trace.go:171","msg":"trace[937207368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"268.333684ms","start":"2026-04-28T06:43:04.708479Z","end":"2026-04-28T06:43:04.976813Z","steps":["trace[937207368] 'agreement among raft nodes before linearized reading' (duration: 267.998082ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:05.990172Z","caller":"traceutil/trace.go:171","msg":"trace[1825045021] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"537.04619ms","start":"2026-04-28T06:43:05.453101Z","end":"2026-04-28T06:43:05.990147Z","steps":["trace[1825045021] 'process raft request' (duration: 536.189788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:05.990316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:05.453072Z","time spent":"537.170146ms","remote":"127.0.0.1:35152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:07.302595Z","caller":"traceutil/trace.go:171","msg":"trace[940551633] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"210.346709ms","start":"2026-04-28T06:43:07.092233Z","end":"2026-04-28T06:43:07.30258Z","steps":["trace[940551633] 'process raft request' (duration: 208.537814ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:07.935547Z","caller":"traceutil/trace.go:171","msg":"trace[1931147830] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"159.330267ms","start":"2026-04-28T06:43:07.77619Z","end":"2026-04-28T06:43:07.935521Z","steps":["trace[1931147830] 'read index received' (duration: 149.4584ms)","trace[1931147830] 'applied index is now lower than readState.Index' (duration: 9.870557ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:07.935611Z","caller":"traceutil/trace.go:171","msg":"trace[1801965131] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"266.561332ms","start":"2026-04-28T06:43:07.669021Z","end":"2026-04-28T06:43:07.935582Z","steps":["trace[1801965131] 'process raft request' (duration: 256.634973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:07.935687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.480293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:07.935731Z","caller":"traceutil/trace.go:171","msg":"trace[1264483375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"159.539885ms","start":"2026-04-28T06:43:07.776179Z","end":"2026-04-28T06:43:07.935718Z","steps":["trace[1264483375] 'agreement among raft nodes before linearized reading' (duration: 159.448262ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:09.487992Z","caller":"traceutil/trace.go:171","msg":"trace[315495252] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"105.81273ms","start":"2026-04-28T06:43:09.382143Z","end":"2026-04-28T06:43:09.487955Z","steps":["trace[315495252] 'read index received' (duration: 105.666115ms)","trace[315495252] 'applied index is now lower than readState.Index' (duration: 145.715µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:09.488299Z","caller":"traceutil/trace.go:171","msg":"trace[1085776419] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"200.0329ms","start":"2026-04-28T06:43:09.288246Z","end":"2026-04-28T06:43:09.488279Z","steps":["trace[1085776419] 'process raft request' (duration: 199.625566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:09.48859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.420832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:09.488664Z","caller":"traceutil/trace.go:171","msg":"trace[1479036237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:656; }","duration":"106.530836ms","start":"2026-04-28T06:43:09.382118Z","end":"2026-04-28T06:43:09.488648Z","steps":["trace[1479036237] 'agreement among raft nodes before linearized reading' (duration: 106.36233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:09.491886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.644597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:09.491922Z","caller":"traceutil/trace.go:171","msg":"trace[173830806] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:657; }","duration":"109.720899ms","start":"2026-04-28T06:43:09.382194Z","end":"2026-04-28T06:43:09.491915Z","steps":["trace[173830806] 'agreement among raft nodes before linearized reading' (duration: 109.645436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:11.830355Z","caller":"traceutil/trace.go:171","msg":"trace[1215491080] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"103.052081ms","start":"2026-04-28T06:43:11.727277Z","end":"2026-04-28T06:43:11.830329Z","steps":["trace[1215491080] 'process raft request' (duration: 76.122668ms)","trace[1215491080] 'compare' (duration: 26.665924ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:11.857343Z","caller":"traceutil/trace.go:171","msg":"trace[1183293631] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"110.789229ms","start":"2026-04-28T06:43:11.746514Z","end":"2026-04-28T06:43:11.857304Z","steps":["trace[1183293631] 'process raft request' (duration: 110.299142ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:16.188005Z","caller":"traceutil/trace.go:171","msg":"trace[1575095193] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"135.593455ms","start":"2026-04-28T06:43:16.052396Z","end":"2026-04-28T06:43:16.187989Z","steps":["trace[1575095193] 'process raft request' (duration: 135.285185ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:16.339343Z","caller":"traceutil/trace.go:171","msg":"trace[136422602] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"146.005367ms","start":"2026-04-28T06:43:16.193302Z","end":"2026-04-28T06:43:16.339307Z","steps":["trace[136422602] 'process raft request' (duration: 83.289841ms)","trace[136422602] 'compare' (duration: 62.371365ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:16.416137Z","caller":"traceutil/trace.go:171","msg":"trace[462271111] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"212.787707ms","start":"2026-04-28T06:43:16.203319Z","end":"2026-04-28T06:43:16.416107Z","steps":["trace[462271111] 'process raft request' (duration: 212.655372ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:16.562068Z","caller":"traceutil/trace.go:171","msg":"trace[1332961051] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"137.011172ms","start":"2026-04-28T06:43:16.425008Z","end":"2026-04-28T06:43:16.562019Z","steps":["trace[1332961051] 'process raft request' (duration: 131.651786ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:16.634693Z","caller":"traceutil/trace.go:171","msg":"trace[460451966] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"202.197809ms","start":"2026-04-28T06:43:16.432469Z","end":"2026-04-28T06:43:16.634667Z","steps":["trace[460451966] 'process raft request' (duration: 201.92293ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:16.76099Z","caller":"traceutil/trace.go:171","msg":"trace[1285054230] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"190.261698ms","start":"2026-04-28T06:43:16.57071Z","end":"2026-04-28T06:43:16.760971Z","steps":["trace[1285054230] 'process raft request' (duration: 190.122372ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:17.332228Z","caller":"traceutil/trace.go:171","msg":"trace[1015672154] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:744; }","duration":"280.264859ms","start":"2026-04-28T06:43:17.051939Z","end":"2026-04-28T06:43:17.332204Z","steps":["trace[1015672154] 'read index received' (duration: 31.940977ms)","trace[1015672154] 'applied index is now lower than readState.Index' (duration: 248.319611ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:17.332414Z","caller":"traceutil/trace.go:171","msg":"trace[1663049767] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"282.458159ms","start":"2026-04-28T06:43:17.049814Z","end":"2026-04-28T06:43:17.332273Z","steps":["trace[1663049767] 'process raft request' (duration: 282.241992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:17.332489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.505607ms","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-04-28T06:43:17.332664Z","caller":"traceutil/trace.go:171","msg":"trace[116744898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"280.731024ms","start":"2026-04-28T06:43:17.051923Z","end":"2026-04-28T06:43:17.332654Z","steps":["trace[116744898] 'agreement among raft nodes before linearized reading' (duration: 280.373192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:17.332693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.191406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T06:43:17.332813Z","caller":"traceutil/trace.go:171","msg":"trace[328544316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:716; }","duration":"280.327761ms","start":"2026-04-28T06:43:17.052471Z","end":"2026-04-28T06:43:17.332799Z","steps":["trace[328544316] 'agreement among raft nodes before linearized reading' (duration: 279.891736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:19.647048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291123786809912,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:43:19.792312Z","caller":"traceutil/trace.go:171","msg":"trace[294111490] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"700.722376ms","start":"2026-04-28T06:43:19.091571Z","end":"2026-04-28T06:43:19.792293Z","steps":["trace[294111490] 'process raft request' (duration: 700.503999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:19.792454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:19.091548Z","time spent":"700.833739ms","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:43:19.792702Z","caller":"traceutil/trace.go:171","msg":"trace[166230462] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"646.235176ms","start":"2026-04-28T06:43:19.146023Z","end":"2026-04-28T06:43:19.792258Z","steps":["trace[166230462] 'read index received' (duration: 645.944535ms)","trace[166230462] 'applied index is now lower than readState.Index' (duration: 287.87µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:19.792864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.835325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:43:19.7929Z","caller":"traceutil/trace.go:171","msg":"trace[1403485144] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:721; }","duration":"646.870996ms","start":"2026-04-28T06:43:19.146019Z","end":"2026-04-28T06:43:19.79289Z","steps":["trace[1403485144] 'agreement among raft nodes before linearized reading' (duration: 646.734472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:19.792927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:19.145981Z","time spent":"646.940088ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.157\" "} {"level":"warn","ts":"2026-04-28T06:43:19.793237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.69092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T06:43:19.793275Z","caller":"traceutil/trace.go:171","msg":"trace[1002483712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"455.774832ms","start":"2026-04-28T06:43:19.337489Z","end":"2026-04-28T06:43:19.793264Z","steps":["trace[1002483712] 'agreement among raft nodes before linearized reading' (duration: 455.687389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:19.793302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:19.337456Z","time spent":"455.839335ms","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:43:19.793588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.522854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T06:43:19.793661Z","caller":"traceutil/trace.go:171","msg":"trace[101080152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"380.615507ms","start":"2026-04-28T06:43:19.413033Z","end":"2026-04-28T06:43:19.793649Z","steps":["trace[101080152] 'agreement among raft nodes before linearized reading' (duration: 380.497003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:19.793696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:19.413017Z","time spent":"380.669909ms","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:43:19.79388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.198348ms","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-04-28T06:43:19.79406Z","caller":"traceutil/trace.go:171","msg":"trace[1644076054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"440.451726ms","start":"2026-04-28T06:43:19.353577Z","end":"2026-04-28T06:43:19.794028Z","steps":["trace[1644076054] 'agreement among raft nodes before linearized reading' (duration: 440.002332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:19.794128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:19.353563Z","time spent":"440.551359ms","remote":"127.0.0.1:35296","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":"info","ts":"2026-04-28T06:43:19.949192Z","caller":"traceutil/trace.go:171","msg":"trace[1932409894] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"150.978281ms","start":"2026-04-28T06:43:19.798183Z","end":"2026-04-28T06:43:19.949162Z","steps":["trace[1932409894] 'process raft request' (duration: 150.474604ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:19.949395Z","caller":"traceutil/trace.go:171","msg":"trace[1440133752] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"151.055964ms","start":"2026-04-28T06:43:19.798283Z","end":"2026-04-28T06:43:19.949339Z","steps":["trace[1440133752] 'process raft request' (duration: 150.726893ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:19.949781Z","caller":"traceutil/trace.go:171","msg":"trace[2007109751] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"150.814885ms","start":"2026-04-28T06:43:19.798929Z","end":"2026-04-28T06:43:19.949744Z","steps":["trace[2007109751] 'process raft request' (duration: 150.167575ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:19.955356Z","caller":"traceutil/trace.go:171","msg":"trace[2073807541] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"134.711614ms","start":"2026-04-28T06:43:19.820631Z","end":"2026-04-28T06:43:19.955343Z","steps":["trace[2073807541] 'process raft request' (duration: 134.626081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.495301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019247342s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:43:24.495492Z","caller":"traceutil/trace.go:171","msg":"trace[71416250] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"233.901956ms","start":"2026-04-28T06:43:24.261573Z","end":"2026-04-28T06:43:24.495475Z","steps":["trace[71416250] 'process raft request' (duration: 233.839184ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:24.495532Z","caller":"traceutil/trace.go:171","msg":"trace[539493013] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"714.588821ms","start":"2026-04-28T06:43:23.780915Z","end":"2026-04-28T06:43:24.495504Z","steps":["trace[539493013] 'read index received' (duration: 37.621µs)","trace[539493013] 'applied index is now lower than readState.Index' (duration: 714.548129ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:24.49567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.752746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:24.495784Z","caller":"traceutil/trace.go:171","msg":"trace[1636840409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"714.824138ms","start":"2026-04-28T06:43:23.780909Z","end":"2026-04-28T06:43:24.495733Z","steps":["trace[1636840409] 'agreement among raft nodes before linearized reading' (duration: 714.736836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.495891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:23.780854Z","time spent":"714.969212ms","remote":"127.0.0.1:35046","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-04-28T06:43:24.496124Z","caller":"traceutil/trace.go:171","msg":"trace[1701144020] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"1.206550675s","start":"2026-04-28T06:43:23.289523Z","end":"2026-04-28T06:43:24.496073Z","steps":["trace[1701144020] 'process raft request' (duration: 185.967243ms)","trace[1701144020] 'compare' (duration: 1.018703835s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:43:24.496287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:23.289501Z","time spent":"1.20670236s","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:43:24.585663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.396662ms","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-04-28T06:43:24.585689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.661386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T06:43:24.585737Z","caller":"traceutil/trace.go:171","msg":"trace[971695956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:742; }","duration":"779.498276ms","start":"2026-04-28T06:43:23.806219Z","end":"2026-04-28T06:43:24.585718Z","steps":["trace[971695956] 'agreement among raft nodes before linearized reading' (duration: 779.29111ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:24.585747Z","caller":"traceutil/trace.go:171","msg":"trace[1744664093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:742; }","duration":"201.763919ms","start":"2026-04-28T06:43:24.383969Z","end":"2026-04-28T06:43:24.585733Z","steps":["trace[1744664093] 'agreement among raft nodes before linearized reading' (duration: 201.572024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.58578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:23.806205Z","time spent":"779.564637ms","remote":"127.0.0.1:35296","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-04-28T06:43:24.585776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.432278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-28T06:43:24.585811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.505291ms","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-04-28T06:43:24.585841Z","caller":"traceutil/trace.go:171","msg":"trace[2095546918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"566.516651ms","start":"2026-04-28T06:43:24.019308Z","end":"2026-04-28T06:43:24.585825Z","steps":["trace[2095546918] 'agreement among raft nodes before linearized reading' (duration: 566.167591ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:24.58586Z","caller":"traceutil/trace.go:171","msg":"trace[384752689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"566.582104ms","start":"2026-04-28T06:43:24.019265Z","end":"2026-04-28T06:43:24.585847Z","steps":["trace[384752689] 'agreement among raft nodes before linearized reading' (duration: 566.370877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.585889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:24.019304Z","time spent":"566.573214ms","remote":"127.0.0.1:35296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:43:24.585901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:24.01925Z","time spent":"566.640015ms","remote":"127.0.0.1:35296","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":"warn","ts":"2026-04-28T06:43:24.586234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.901782ms","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-04-28T06:43:24.586302Z","caller":"traceutil/trace.go:171","msg":"trace[153137275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"567.003946ms","start":"2026-04-28T06:43:24.019283Z","end":"2026-04-28T06:43:24.586287Z","steps":["trace[153137275] 'agreement among raft nodes before linearized reading' (duration: 566.194282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:24.586352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:43:24.019267Z","time spent":"567.073168ms","remote":"127.0.0.1:35296","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":"info","ts":"2026-04-28T06:43:26.833863Z","caller":"traceutil/trace.go:171","msg":"trace[1542270287] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"153.332666ms","start":"2026-04-28T06:43:26.680504Z","end":"2026-04-28T06:43:26.833836Z","steps":["trace[1542270287] 'process raft request' (duration: 129.226877ms)","trace[1542270287] 'compare' (duration: 23.854742ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:26.83394Z","caller":"traceutil/trace.go:171","msg":"trace[1546682585] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"153.386228ms","start":"2026-04-28T06:43:26.680519Z","end":"2026-04-28T06:43:26.833906Z","steps":["trace[1546682585] 'process raft request' (duration: 153.220023ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:26.834639Z","caller":"traceutil/trace.go:171","msg":"trace[1388152747] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"154.046488ms","start":"2026-04-28T06:43:26.680561Z","end":"2026-04-28T06:43:26.834608Z","steps":["trace[1388152747] 'process raft request' (duration: 153.279515ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:27.309294Z","caller":"traceutil/trace.go:171","msg":"trace[211592001] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"123.093888ms","start":"2026-04-28T06:43:27.186176Z","end":"2026-04-28T06:43:27.30927Z","steps":["trace[211592001] 'process raft request' (duration: 122.912063ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:28.591571Z","caller":"traceutil/trace.go:171","msg":"trace[1064154271] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"152.671905ms","start":"2026-04-28T06:43:28.438879Z","end":"2026-04-28T06:43:28.591551Z","steps":["trace[1064154271] 'process raft request' (duration: 152.526801ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:29.235332Z","caller":"traceutil/trace.go:171","msg":"trace[230034193] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"131.073152ms","start":"2026-04-28T06:43:29.104216Z","end":"2026-04-28T06:43:29.235289Z","steps":["trace[230034193] 'process raft request' (duration: 130.676621ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:29.235577Z","caller":"traceutil/trace.go:171","msg":"trace[35122042] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"130.276531ms","start":"2026-04-28T06:43:29.105288Z","end":"2026-04-28T06:43:29.235564Z","steps":["trace[35122042] 'process raft request' (duration: 129.819897ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:30.887285Z","caller":"traceutil/trace.go:171","msg":"trace[33485487] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"106.196651ms","start":"2026-04-28T06:43:30.781064Z","end":"2026-04-28T06:43:30.887261Z","steps":["trace[33485487] 'read index received' (duration: 105.999295ms)","trace[33485487] 'applied index is now lower than readState.Index' (duration: 196.566µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:30.887388Z","caller":"traceutil/trace.go:171","msg":"trace[839288434] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"143.896686ms","start":"2026-04-28T06:43:30.743483Z","end":"2026-04-28T06:43:30.88738Z","steps":["trace[839288434] 'process raft request' (duration: 143.628939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:30.887528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.441359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:43:30.887576Z","caller":"traceutil/trace.go:171","msg":"trace[76928822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:805; }","duration":"106.52566ms","start":"2026-04-28T06:43:30.781039Z","end":"2026-04-28T06:43:30.887565Z","steps":["trace[76928822] 'agreement among raft nodes before linearized reading' (duration: 106.436058ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:33.53117Z","caller":"traceutil/trace.go:171","msg":"trace[917937885] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"173.218179ms","start":"2026-04-28T06:43:33.357911Z","end":"2026-04-28T06:43:33.531129Z","steps":["trace[917937885] 'process raft request' (duration: 119.668961ms)","trace[917937885] 'compare' (duration: 53.352123ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:33.537155Z","caller":"traceutil/trace.go:171","msg":"trace[1376597188] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"179.135861ms","start":"2026-04-28T06:43:33.358005Z","end":"2026-04-28T06:43:33.537141Z","steps":["trace[1376597188] 'process raft request' (duration: 179.028448ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:44.466092Z","caller":"traceutil/trace.go:171","msg":"trace[904804017] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"128.388612ms","start":"2026-04-28T06:43:44.337675Z","end":"2026-04-28T06:43:44.466064Z","steps":["trace[904804017] 'process raft request' (duration: 128.243379ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:45.348167Z","caller":"traceutil/trace.go:171","msg":"trace[1646919464] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"243.025951ms","start":"2026-04-28T06:43:45.105115Z","end":"2026-04-28T06:43:45.348141Z","steps":["trace[1646919464] 'read index received' (duration: 242.699793ms)","trace[1646919464] 'applied index is now lower than readState.Index' (duration: 325.258µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:43:45.348244Z","caller":"traceutil/trace.go:171","msg":"trace[929215224] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"245.421159ms","start":"2026-04-28T06:43:45.102769Z","end":"2026-04-28T06:43:45.34819Z","steps":["trace[929215224] 'process raft request' (duration: 245.214264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:43:45.348453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.817731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-28T06:43:45.348601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.437331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-28T06:43:45.348642Z","caller":"traceutil/trace.go:171","msg":"trace[58646568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"240.037437ms","start":"2026-04-28T06:43:45.108589Z","end":"2026-04-28T06:43:45.348626Z","steps":["trace[58646568] 'agreement among raft nodes before linearized reading' (duration: 239.699059ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:45.348734Z","caller":"traceutil/trace.go:171","msg":"trace[612644150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"243.633526ms","start":"2026-04-28T06:43:45.105072Z","end":"2026-04-28T06:43:45.348705Z","steps":["trace[612644150] 'agreement among raft nodes before linearized reading' (duration: 243.192835ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:45.477132Z","caller":"traceutil/trace.go:171","msg":"trace[593204954] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"122.970481ms","start":"2026-04-28T06:43:45.35414Z","end":"2026-04-28T06:43:45.477111Z","steps":["trace[593204954] 'process raft request' (duration: 122.92549ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:43:45.477402Z","caller":"traceutil/trace.go:171","msg":"trace[1698164943] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"123.945604ms","start":"2026-04-28T06:43:45.35339Z","end":"2026-04-28T06:43:45.477335Z","steps":["trace[1698164943] 'process raft request' (duration: 76.343593ms)","trace[1698164943] 'compare' (duration: 47.186531ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:44:09.289692Z","caller":"traceutil/trace.go:171","msg":"trace[115160362] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"120.609014ms","start":"2026-04-28T06:44:09.169052Z","end":"2026-04-28T06:44:09.289661Z","steps":["trace[115160362] 'read index received' (duration: 120.343848ms)","trace[115160362] 'applied index is now lower than readState.Index' (duration: 263.606µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:44:09.289969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.89313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-28T06:44:09.290001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.297238ms","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-04-28T06:44:09.290049Z","caller":"traceutil/trace.go:171","msg":"trace[40012632] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1042; }","duration":"121.021562ms","start":"2026-04-28T06:44:09.169011Z","end":"2026-04-28T06:44:09.290033Z","steps":["trace[40012632] 'agreement among raft nodes before linearized reading' (duration: 120.804008ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:44:09.290058Z","caller":"traceutil/trace.go:171","msg":"trace[1010106463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1042; }","duration":"118.37906ms","start":"2026-04-28T06:44:09.171665Z","end":"2026-04-28T06:44:09.290044Z","steps":["trace[1010106463] 'agreement among raft nodes before linearized reading' (duration: 118.238647ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:44:16.873647Z","caller":"traceutil/trace.go:171","msg":"trace[1590994104] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"216.945671ms","start":"2026-04-28T06:44:16.656681Z","end":"2026-04-28T06:44:16.873626Z","steps":["trace[1590994104] 'process raft request' (duration: 216.483733ms)"],"step_count":1}