{"level":"warn","ts":"2026-03-05T17:42:53.665484Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T17:42:53.665588Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-03-05T17:42:53.665681Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-05T17:42:53.665699Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-03-05T17:42:53.665718Z","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-03-05T17:42:53.666536Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-03-05T17:42:53.66663Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-03-05T17:42:53.671419Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.498557ms"} {"level":"info","ts":"2026-03-05T17:42:53.681722Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-03-05T17:42:53.681831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-03-05T17:42:53.681869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-03-05T17:42:53.68189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-05T17:42:53.681909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-03-05T17:42:53.681961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-03-05T17:42:53.688729Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-05T17:42:53.693184Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-05T17:42:53.695209Z","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-03-05T17:42:53.697703Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-05T17:42:53.697862Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-05T17:42:53.698053Z","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-03-05T17:42:53.698143Z","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-03-05T17:42:53.698153Z","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-03-05T17:42:53.69894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-03-05T17:42:53.705023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-03-05T17:42:53.705591Z","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-03-05T17:42:53.705767Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-05T17:42:53.705804Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-05T17:42:53.705792Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-03-05T17:42:53.706039Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-03-05T17:42:54.282545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-03-05T17:42:54.282617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-03-05T17:42:54.282658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-03-05T17:42:54.282685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-03-05T17:42:54.282967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-03-05T17:42:54.282986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-03-05T17:42:54.283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-03-05T17:42:54.285392Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-03-05T17:42:54.285456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T17:42:54.285392Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T17:42:54.285768Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-05T17:42:54.286396Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-05T17:42:54.286465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-05T17:42:54.289253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-03-05T17:42:54.289325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-05T17:42:54.296629Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T17:42:54.29675Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-05T17:42:54.296797Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-05T17:42:55.940128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.779205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-05T17:42:55.940307Z","caller":"traceutil/trace.go:171","msg":"trace[605141528] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"272.663512ms","start":"2026-03-05T17:42:55.667594Z","end":"2026-03-05T17:42:55.940258Z","steps":["trace[605141528] 'process raft request' (duration: 103.074932ms)","trace[605141528] 'compare' (duration: 168.559779ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:42:55.941093Z","caller":"traceutil/trace.go:171","msg":"trace[2068611431] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"273.185532ms","start":"2026-03-05T17:42:55.667855Z","end":"2026-03-05T17:42:55.94104Z","steps":["trace[2068611431] 'process raft request' (duration: 272.89671ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:55.941198Z","caller":"traceutil/trace.go:171","msg":"trace[1887662416] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:7; }","duration":"272.944792ms","start":"2026-03-05T17:42:55.668223Z","end":"2026-03-05T17:42:55.941168Z","steps":["trace[1887662416] 'read index received' (duration: 102.555611ms)","trace[1887662416] 'applied index is now lower than readState.Index' (duration: 170.387461ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:42:55.941366Z","caller":"traceutil/trace.go:171","msg":"trace[2135644645] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"273.42507ms","start":"2026-03-05T17:42:55.66792Z","end":"2026-03-05T17:42:55.941345Z","steps":["trace[2135644645] 'process raft request' (duration: 273.053131ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:55.941516Z","caller":"traceutil/trace.go:171","msg":"trace[1802777115] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"273.535808ms","start":"2026-03-05T17:42:55.667967Z","end":"2026-03-05T17:42:55.941503Z","steps":["trace[1802777115] 'process raft request' (duration: 273.04058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:55.941649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.342344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:42:55.941779Z","caller":"traceutil/trace.go:171","msg":"trace[955383530] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"273.615825ms","start":"2026-03-05T17:42:55.668149Z","end":"2026-03-05T17:42:55.941765Z","steps":["trace[955383530] 'process raft request' (duration: 272.9074ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:55.941784Z","caller":"traceutil/trace.go:171","msg":"trace[357808821] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"273.546969ms","start":"2026-03-05T17:42:55.6682Z","end":"2026-03-05T17:42:55.941747Z","steps":["trace[357808821] 'agreement among raft nodes before linearized reading' (duration: 273.205313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:55.941734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.191152ms","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":"warn","ts":"2026-03-05T17:42:55.941779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.266836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:42:55.942075Z","caller":"traceutil/trace.go:171","msg":"trace[796333223] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"259.218874ms","start":"2026-03-05T17:42:55.682835Z","end":"2026-03-05T17:42:55.942054Z","steps":["trace[796333223] 'process raft request' (duration: 258.290562ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:55.94209Z","caller":"traceutil/trace.go:171","msg":"trace[666154507] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"223.630574ms","start":"2026-03-05T17:42:55.718446Z","end":"2026-03-05T17:42:55.942076Z","steps":["trace[666154507] 'agreement among raft nodes before linearized reading' (duration: 223.281507ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:55.942042Z","caller":"traceutil/trace.go:171","msg":"trace[1592286032] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"273.539229ms","start":"2026-03-05T17:42:55.668475Z","end":"2026-03-05T17:42:55.942014Z","steps":["trace[1592286032] 'agreement among raft nodes before linearized reading' (duration: 273.066092ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:55.941929Z","caller":"traceutil/trace.go:171","msg":"trace[1201168946] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"273.219493ms","start":"2026-03-05T17:42:55.668697Z","end":"2026-03-05T17:42:55.941916Z","steps":["trace[1201168946] 'process raft request' (duration: 272.386059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:55.942467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.480779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:42:55.942612Z","caller":"traceutil/trace.go:171","msg":"trace[6420027] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"257.685065ms","start":"2026-03-05T17:42:55.684879Z","end":"2026-03-05T17:42:55.942564Z","steps":["trace[6420027] 'agreement among raft nodes before linearized reading' (duration: 257.451106ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.112665Z","caller":"traceutil/trace.go:171","msg":"trace[1800945209] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"157.49543ms","start":"2026-03-05T17:42:55.955126Z","end":"2026-03-05T17:42:56.112622Z","steps":["trace[1800945209] 'process raft request' (duration: 86.652729ms)","trace[1800945209] 'compare' (duration: 70.58223ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:42:56.112789Z","caller":"traceutil/trace.go:171","msg":"trace[728477038] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"157.421895ms","start":"2026-03-05T17:42:55.955329Z","end":"2026-03-05T17:42:56.112751Z","steps":["trace[728477038] 'process raft request' (duration: 157.181996ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.113131Z","caller":"traceutil/trace.go:171","msg":"trace[683302452] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"157.333038ms","start":"2026-03-05T17:42:55.955766Z","end":"2026-03-05T17:42:56.113099Z","steps":["trace[683302452] 'process raft request' (duration: 156.794126ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.11319Z","caller":"traceutil/trace.go:171","msg":"trace[512270027] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:22; }","duration":"154.407629ms","start":"2026-03-05T17:42:55.958764Z","end":"2026-03-05T17:42:56.113172Z","steps":["trace[512270027] 'read index received' (duration: 83.030107ms)","trace[512270027] 'applied index is now lower than readState.Index' (duration: 71.375772ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:42:56.113417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.624698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-05T17:42:56.113468Z","caller":"traceutil/trace.go:171","msg":"trace[1043421570] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"154.713134ms","start":"2026-03-05T17:42:55.958742Z","end":"2026-03-05T17:42:56.113455Z","steps":["trace[1043421570] 'agreement among raft nodes before linearized reading' (duration: 154.517068ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.114068Z","caller":"traceutil/trace.go:171","msg":"trace[1643570458] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"154.171902ms","start":"2026-03-05T17:42:55.959879Z","end":"2026-03-05T17:42:56.11405Z","steps":["trace[1643570458] 'process raft request' (duration: 153.89603ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.114069Z","caller":"traceutil/trace.go:171","msg":"trace[1348186569] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"153.841027ms","start":"2026-03-05T17:42:55.960206Z","end":"2026-03-05T17:42:56.114047Z","steps":["trace[1348186569] 'process raft request' (duration: 153.698215ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.114329Z","caller":"traceutil/trace.go:171","msg":"trace[502977747] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"117.804771ms","start":"2026-03-05T17:42:55.99651Z","end":"2026-03-05T17:42:56.114315Z","steps":["trace[502977747] 'process raft request' (duration: 117.448964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:56.114119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.216555ms","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-03-05T17:42:56.114203Z","caller":"traceutil/trace.go:171","msg":"trace[609423213] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"153.947865ms","start":"2026-03-05T17:42:55.960242Z","end":"2026-03-05T17:42:56.11419Z","steps":["trace[609423213] 'process raft request' (duration: 153.690745ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.114521Z","caller":"traceutil/trace.go:171","msg":"trace[305447896] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"154.543251ms","start":"2026-03-05T17:42:55.959961Z","end":"2026-03-05T17:42:56.114504Z","steps":["trace[305447896] 'process raft request' (duration: 153.88141ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.114521Z","caller":"traceutil/trace.go:171","msg":"trace[98788252] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"154.475146ms","start":"2026-03-05T17:42:55.960011Z","end":"2026-03-05T17:42:56.114486Z","steps":["trace[98788252] 'process raft request' (duration: 153.865098ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.114611Z","caller":"traceutil/trace.go:171","msg":"trace[1000047569] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"109.843732ms","start":"2026-03-05T17:42:56.004752Z","end":"2026-03-05T17:42:56.114596Z","steps":["trace[1000047569] 'process raft request' (duration: 109.234155ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.114731Z","caller":"traceutil/trace.go:171","msg":"trace[2068774735] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:28; }","duration":"154.860536ms","start":"2026-03-05T17:42:55.959844Z","end":"2026-03-05T17:42:56.114705Z","steps":["trace[2068774735] 'agreement among raft nodes before linearized reading' (duration: 154.183503ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.304085Z","caller":"traceutil/trace.go:171","msg":"trace[672813309] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"124.069115ms","start":"2026-03-05T17:42:56.179981Z","end":"2026-03-05T17:42:56.30405Z","steps":["trace[672813309] 'process raft request' (duration: 124.027982ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:42:56.304265Z","caller":"traceutil/trace.go:171","msg":"trace[2095655535] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"125.973503ms","start":"2026-03-05T17:42:56.178247Z","end":"2026-03-05T17:42:56.30422Z","steps":["trace[2095655535] 'process raft request' (duration: 47.136536ms)","trace[2095655535] 'compare' (duration: 78.531173ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:42:57.265005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.826303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-05T17:42:57.265223Z","caller":"traceutil/trace.go:171","msg":"trace[1065483905] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"713.40646ms","start":"2026-03-05T17:42:56.551785Z","end":"2026-03-05T17:42:57.265191Z","steps":["trace[1065483905] 'process raft request' (duration: 252.324276ms)","trace[1065483905] 'compare' (duration: 460.655469ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:42:57.265374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:56.551774Z","time spent":"713.528769ms","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:42:57.265427Z","caller":"traceutil/trace.go:171","msg":"trace[2144843716] linearizableReadLoop","detail":"{readStateIndex:57; appliedIndex:56; }","duration":"710.271665ms","start":"2026-03-05T17:42:56.555099Z","end":"2026-03-05T17:42:57.265371Z","steps":["trace[2144843716] 'read index received' (duration: 249.098754ms)","trace[2144843716] 'applied index is now lower than readState.Index' (duration: 461.16668ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:42:57.267476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:56.571665Z","time spent":"695.803883ms","remote":"127.0.0.1:35560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-05T17:42:57.267855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.092113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:42:57.267947Z","caller":"traceutil/trace.go:171","msg":"trace[1536259184] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:53; }","duration":"703.209953ms","start":"2026-03-05T17:42:56.564715Z","end":"2026-03-05T17:42:57.267925Z","steps":["trace[1536259184] 'agreement among raft nodes before linearized reading' (duration: 702.930121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:57.26807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:56.564702Z","time spent":"703.278188ms","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-03-05T17:42:57.268106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.925133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/system-leader-election\" ","response":"range_response_count:1 size:1228"} {"level":"info","ts":"2026-03-05T17:42:57.268166Z","caller":"traceutil/trace.go:171","msg":"trace[1130291791] range","detail":"{range_begin:/registry/flowschemas/system-leader-election; range_end:; response_count:1; response_revision:53; }","duration":"713.115488ms","start":"2026-03-05T17:42:56.555033Z","end":"2026-03-05T17:42:57.268148Z","steps":["trace[1130291791] 'agreement among raft nodes before linearized reading' (duration: 710.410475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:57.268291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:56.555019Z","time spent":"713.26198ms","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":1250,"request content":"key:\"/registry/flowschemas/system-leader-election\" "} {"level":"warn","ts":"2026-03-05T17:42:57.268443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.673498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:42:57.268497Z","caller":"traceutil/trace.go:171","msg":"trace[1988622755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:53; }","duration":"597.769266ms","start":"2026-03-05T17:42:56.670712Z","end":"2026-03-05T17:42:57.268481Z","steps":["trace[1988622755] 'agreement among raft nodes before linearized reading' (duration: 597.681749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:57.268537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:56.67069Z","time spent":"597.829441ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T17:42:57.270251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.110771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:42:57.270329Z","caller":"traceutil/trace.go:171","msg":"trace[461660201] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:53; }","duration":"705.22775ms","start":"2026-03-05T17:42:56.565085Z","end":"2026-03-05T17:42:57.270312Z","steps":["trace[461660201] 'agreement among raft nodes before linearized reading' (duration: 703.400118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:57.270381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:56.565071Z","time spent":"705.287695ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-03-05T17:42:57.844461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218279929705157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T17:42:58.345896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218279929705157,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T17:42:58.682429Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.339250176s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-05T17:42:59.64649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.704157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-05T17:42:59.64714Z","caller":"traceutil/trace.go:171","msg":"trace[1857786786] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"2.372923653s","start":"2026-03-05T17:42:57.274185Z","end":"2026-03-05T17:42:59.647109Z","steps":["trace[1857786786] 'process raft request' (duration: 1.408414272s)","trace[1857786786] 'compare' (duration: 963.521963ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:42:59.647213Z","caller":"traceutil/trace.go:171","msg":"trace[529062881] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"2.372745049s","start":"2026-03-05T17:42:57.274447Z","end":"2026-03-05T17:42:59.647192Z","steps":["trace[529062881] 'process raft request' (duration: 2.372442095s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:59.647267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:57.274168Z","time spent":"2.373031052s","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:42:59.647543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:57.274439Z","time spent":"2.372830316s","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T17:42:59.648092Z","caller":"traceutil/trace.go:171","msg":"trace[715248376] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"2.373950634s","start":"2026-03-05T17:42:57.274105Z","end":"2026-03-05T17:42:59.648055Z","steps":["trace[715248376] 'process raft request' (duration: 2.37288879s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:59.648381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:57.274091Z","time spent":"2.374210575s","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":702,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:42:59.771231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000406052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-05T17:42:59.771324Z","caller":"traceutil/trace.go:171","msg":"trace[1868395734] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000548214s","start":"2026-03-05T17:42:57.770759Z","end":"2026-03-05T17:42:59.771308Z","steps":["trace[1868395734] 'agreement among raft nodes before linearized reading' (duration: 2.000442945s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:42:59.771369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:57.770736Z","time spent":"2.00062352s","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T17:43:00.37388Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.689789731s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-05T17:43:01.036608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999682199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-05T17:43:01.036704Z","caller":"traceutil/trace.go:171","msg":"trace[1350270688] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999868864s","start":"2026-03-05T17:42:59.036815Z","end":"2026-03-05T17:43:01.036684Z","steps":["trace[1350270688] 'agreement among raft nodes before linearized reading' (duration: 1.999712791s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:01.036754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:59.036787Z","time spent":"1.99995848s","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-05T17:43:01.137961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.666052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-05T17:43:01.13808Z","caller":"traceutil/trace.go:171","msg":"trace[310818833] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"3.790822421s","start":"2026-03-05T17:42:57.347227Z","end":"2026-03-05T17:43:01.138049Z","steps":["trace[310818833] 'process raft request' (duration: 3.026974505s)","trace[310818833] 'compare' (duration: 762.354507ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:01.138193Z","caller":"traceutil/trace.go:171","msg":"trace[893649224] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"1.485765588s","start":"2026-03-05T17:42:59.652411Z","end":"2026-03-05T17:43:01.138176Z","steps":["trace[893649224] 'process raft request' (duration: 1.485728985s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:01.13826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:59.652403Z","time spent":"1.485832123s","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:01.138437Z","caller":"traceutil/trace.go:171","msg":"trace[2139940663] linearizableReadLoop","detail":"{readStateIndex:63; appliedIndex:59; }","duration":"3.794940874s","start":"2026-03-05T17:42:57.343485Z","end":"2026-03-05T17:43:01.138426Z","steps":["trace[2139940663] 'read index received' (duration: 1.339126285s)","trace[2139940663] 'applied index is now lower than readState.Index' (duration: 2.455808228s)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:01.138709Z","caller":"traceutil/trace.go:171","msg":"trace[1165946514] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"1.487224494s","start":"2026-03-05T17:42:59.651473Z","end":"2026-03-05T17:43:01.138697Z","steps":["trace[1165946514] 'process raft request' (duration: 1.486589284s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:01.138783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:59.651463Z","time spent":"1.487279869s","remote":"127.0.0.1:35888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1244,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T17:43:01.138939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.864555367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:43:01.138975Z","caller":"traceutil/trace.go:171","msg":"trace[1943398876] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:60; }","duration":"3.86459114s","start":"2026-03-05T17:42:57.274374Z","end":"2026-03-05T17:43:01.138965Z","steps":["trace[1943398876] 'agreement among raft nodes before linearized reading' (duration: 3.864536745s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:01.139004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:57.274367Z","time spent":"3.864629702s","remote":"127.0.0.1:35818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-03-05T17:43:01.139128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488078732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-05T17:43:01.13916Z","caller":"traceutil/trace.go:171","msg":"trace[1693349649] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:60; }","duration":"1.488132677s","start":"2026-03-05T17:42:59.65102Z","end":"2026-03-05T17:43:01.139153Z","steps":["trace[1693349649] 'agreement among raft nodes before linearized reading' (duration: 1.488088813s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:01.139189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:59.651004Z","time spent":"1.48817919s","remote":"127.0.0.1:35820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"warn","ts":"2026-03-05T17:43:01.139455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367395452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-05T17:43:01.139456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:57.347198Z","time spent":"3.790954041s","remote":"127.0.0.1:35560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:01.139492Z","caller":"traceutil/trace.go:171","msg":"trace[798499310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:60; }","duration":"1.367459547s","start":"2026-03-05T17:42:59.772023Z","end":"2026-03-05T17:43:01.139483Z","steps":["trace[798499310] 'agreement among raft nodes before linearized reading' (duration: 1.367407393s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:01.139516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:42:59.772007Z","time spent":"1.36750456s","remote":"127.0.0.1:35448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-05T17:43:02.021726Z","caller":"traceutil/trace.go:171","msg":"trace[2136316904] linearizableReadLoop","detail":"{readStateIndex:134; appliedIndex:133; }","duration":"318.741549ms","start":"2026-03-05T17:43:01.702946Z","end":"2026-03-05T17:43:02.021687Z","steps":["trace[2136316904] 'read index received' (duration: 244.4203ms)","trace[2136316904] 'applied index is now lower than readState.Index' (duration: 74.319889ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:02.021767Z","caller":"traceutil/trace.go:171","msg":"trace[1191859946] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"325.00644ms","start":"2026-03-05T17:43:01.69672Z","end":"2026-03-05T17:43:02.021726Z","steps":["trace[1191859946] 'process raft request' (duration: 250.708723ms)","trace[1191859946] 'compare' (duration: 74.054318ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:02.021953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.99138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:02.022027Z","caller":"traceutil/trace.go:171","msg":"trace[1279647754] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:0; response_revision:129; }","duration":"319.107609ms","start":"2026-03-05T17:43:01.7029Z","end":"2026-03-05T17:43:02.022008Z","steps":["trace[1279647754] 'agreement among raft nodes before linearized reading' (duration: 318.938705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:02.022053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:01.696707Z","time spent":"325.231809ms","remote":"127.0.0.1:35560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:02.022071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:01.702886Z","time spent":"319.177034ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:volume-scheduler\" "} {"level":"info","ts":"2026-03-05T17:43:02.219983Z","caller":"traceutil/trace.go:171","msg":"trace[975790733] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"195.310953ms","start":"2026-03-05T17:43:02.024611Z","end":"2026-03-05T17:43:02.219922Z","steps":["trace[975790733] 'process raft request' (duration: 124.015438ms)","trace[975790733] 'compare' (duration: 70.945657ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:02.220042Z","caller":"traceutil/trace.go:171","msg":"trace[1965938278] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"194.759749ms","start":"2026-03-05T17:43:02.025262Z","end":"2026-03-05T17:43:02.220022Z","steps":["trace[1965938278] 'process raft request' (duration: 194.537532ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:02.219957Z","caller":"traceutil/trace.go:171","msg":"trace[1941498288] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"194.488688ms","start":"2026-03-05T17:43:02.02543Z","end":"2026-03-05T17:43:02.219918Z","steps":["trace[1941498288] 'process raft request' (duration: 194.431494ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:02.53783Z","caller":"traceutil/trace.go:171","msg":"trace[270199750] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"242.772296ms","start":"2026-03-05T17:43:02.295031Z","end":"2026-03-05T17:43:02.537803Z","steps":["trace[270199750] 'process raft request' (duration: 242.69768ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:02.538147Z","caller":"traceutil/trace.go:171","msg":"trace[1493154113] linearizableReadLoop","detail":"{readStateIndex:139; appliedIndex:137; }","duration":"267.698437ms","start":"2026-03-05T17:43:02.270435Z","end":"2026-03-05T17:43:02.538134Z","steps":["trace[1493154113] 'read index received' (duration: 21.179371ms)","trace[1493154113] 'applied index is now lower than readState.Index' (duration: 246.517336ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:02.538292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.836549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:02.538332Z","caller":"traceutil/trace.go:171","msg":"trace[1009723359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:135; }","duration":"267.930777ms","start":"2026-03-05T17:43:02.270391Z","end":"2026-03-05T17:43:02.538322Z","steps":["trace[1009723359] 'agreement among raft nodes before linearized reading' (duration: 267.794556ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:02.538031Z","caller":"traceutil/trace.go:171","msg":"trace[1117189193] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"310.754445ms","start":"2026-03-05T17:43:02.227215Z","end":"2026-03-05T17:43:02.53797Z","steps":["trace[1117189193] 'process raft request' (duration: 262.394672ms)","trace[1117189193] 'compare' (duration: 47.851603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:02.538968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:02.227199Z","time spent":"311.681839ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":666,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:03.178103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.060263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:43:03.178244Z","caller":"traceutil/trace.go:171","msg":"trace[133485989] linearizableReadLoop","detail":"{readStateIndex:144; appliedIndex:143; }","duration":"306.15897ms","start":"2026-03-05T17:43:02.87206Z","end":"2026-03-05T17:43:03.178219Z","steps":["trace[133485989] 'read index received' (duration: 56.155µs)","trace[133485989] 'applied index is now lower than readState.Index' (duration: 306.101185ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:03.178258Z","caller":"traceutil/trace.go:171","msg":"trace[1640415037] transaction","detail":"{read_only:false; response_revision:139; number_of_response:1; }","duration":"553.507763ms","start":"2026-03-05T17:43:02.624724Z","end":"2026-03-05T17:43:03.178231Z","steps":["trace[1640415037] 'process raft request' (duration: 82.18773ms)","trace[1640415037] 'compare' (duration: 470.833884ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:03.178384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:02.624706Z","time spent":"553.60285ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:03.178454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.378377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-03-05T17:43:03.178495Z","caller":"traceutil/trace.go:171","msg":"trace[1998691642] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:139; }","duration":"306.482656ms","start":"2026-03-05T17:43:02.871999Z","end":"2026-03-05T17:43:03.178481Z","steps":["trace[1998691642] 'agreement among raft nodes before linearized reading' (duration: 306.27947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:03.178541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:02.871969Z","time spent":"306.560692ms","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":5535,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-05T17:43:03.760936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.824456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:43:03.761051Z","caller":"traceutil/trace.go:171","msg":"trace[600749794] linearizableReadLoop","detail":"{readStateIndex:150; appliedIndex:149; }","duration":"190.123247ms","start":"2026-03-05T17:43:03.570908Z","end":"2026-03-05T17:43:03.761032Z","steps":["trace[600749794] 'read index received' (duration: 41.163µs)","trace[600749794] 'applied index is now lower than readState.Index' (duration: 190.080783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:03.761204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.297361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:03.761239Z","caller":"traceutil/trace.go:171","msg":"trace[1713190372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:145; }","duration":"190.39907ms","start":"2026-03-05T17:43:03.570831Z","end":"2026-03-05T17:43:03.76123Z","steps":["trace[1713190372] 'agreement among raft nodes before linearized reading' (duration: 190.251118ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:03.761346Z","caller":"traceutil/trace.go:171","msg":"trace[1488669675] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"410.97811ms","start":"2026-03-05T17:43:03.350297Z","end":"2026-03-05T17:43:03.761275Z","steps":["trace[1488669675] 'process raft request' (duration: 68.741131ms)","trace[1488669675] 'compare' (duration: 341.667173ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:03.761585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:03.350258Z","time spent":"411.205868ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:03.978572Z","caller":"traceutil/trace.go:171","msg":"trace[873930576] linearizableReadLoop","detail":"{readStateIndex:153; appliedIndex:152; }","duration":"147.810306ms","start":"2026-03-05T17:43:03.830724Z","end":"2026-03-05T17:43:03.978535Z","steps":["trace[873930576] 'read index received' (duration: 143.948505ms)","trace[873930576] 'applied index is now lower than readState.Index' (duration: 3.860571ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:03.978794Z","caller":"traceutil/trace.go:171","msg":"trace[1348338588] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"187.42748ms","start":"2026-03-05T17:43:03.791292Z","end":"2026-03-05T17:43:03.97872Z","steps":["trace[1348338588] 'process raft request' (duration: 183.477161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:03.978867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.11463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:03.978922Z","caller":"traceutil/trace.go:171","msg":"trace[1101521082] range","detail":"{range_begin:/registry/clusterroles/system:controller:deployment-controller; range_end:; response_count:0; response_revision:148; }","duration":"148.242531ms","start":"2026-03-05T17:43:03.830664Z","end":"2026-03-05T17:43:03.978907Z","steps":["trace[1101521082] 'agreement among raft nodes before linearized reading' (duration: 148.062846ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:04.325962Z","caller":"traceutil/trace.go:171","msg":"trace[1586975984] transaction","detail":"{read_only:false; response_revision:149; number_of_response:1; }","duration":"342.423231ms","start":"2026-03-05T17:43:03.983509Z","end":"2026-03-05T17:43:04.325932Z","steps":["trace[1586975984] 'process raft request' (duration: 282.188737ms)","trace[1586975984] 'compare' (duration: 60.094633ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:04.326206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:03.983479Z","time spent":"342.647209ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:04.724463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.960975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:43:04.724754Z","caller":"traceutil/trace.go:171","msg":"trace[147841201] linearizableReadLoop","detail":"{readStateIndex:156; appliedIndex:155; }","duration":"353.722451ms","start":"2026-03-05T17:43:04.370903Z","end":"2026-03-05T17:43:04.724625Z","steps":["trace[147841201] 'read index received' (duration: 169.516677ms)","trace[147841201] 'applied index is now lower than readState.Index' (duration: 184.204144ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:04.725022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.112063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:04.72507Z","caller":"traceutil/trace.go:171","msg":"trace[487457908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:151; }","duration":"354.218801ms","start":"2026-03-05T17:43:04.370838Z","end":"2026-03-05T17:43:04.725057Z","steps":["trace[487457908] 'agreement among raft nodes before linearized reading' (duration: 354.024686ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:04.725034Z","caller":"traceutil/trace.go:171","msg":"trace[1252003385] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"375.066059ms","start":"2026-03-05T17:43:04.349797Z","end":"2026-03-05T17:43:04.724863Z","steps":["trace[1252003385] 'process raft request' (duration: 190.581212ms)","trace[1252003385] 'compare' (duration: 183.746387ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:04.725115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:04.370809Z","time spent":"354.295958ms","remote":"127.0.0.1:35460","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-03-05T17:43:04.725289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:04.349766Z","time spent":"375.403736ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":741,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:05.194502Z","caller":"traceutil/trace.go:171","msg":"trace[1882732993] transaction","detail":"{read_only:false; response_revision:168; number_of_response:1; }","duration":"107.484818ms","start":"2026-03-05T17:43:05.086979Z","end":"2026-03-05T17:43:05.194464Z","steps":["trace[1882732993] 'process raft request' (duration: 74.475337ms)","trace[1882732993] 'compare' (duration: 32.821096ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:05.421017Z","caller":"traceutil/trace.go:171","msg":"trace[516180689] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"177.577311ms","start":"2026-03-05T17:43:05.243413Z","end":"2026-03-05T17:43:05.42099Z","steps":["trace[516180689] 'process raft request' (duration: 153.18535ms)","trace[516180689] 'compare' (duration: 24.277532ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:07.244947Z","caller":"traceutil/trace.go:171","msg":"trace[312599183] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"202.594076ms","start":"2026-03-05T17:43:07.041953Z","end":"2026-03-05T17:43:07.244547Z","steps":["trace[312599183] 'process raft request' (duration: 202.435773ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:07.451544Z","caller":"traceutil/trace.go:171","msg":"trace[1209615856] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"120.393764ms","start":"2026-03-05T17:43:07.331111Z","end":"2026-03-05T17:43:07.451504Z","steps":["trace[1209615856] 'process raft request' (duration: 26.476604ms)","trace[1209615856] 'compare' (duration: 93.779509ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:08.014381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.305552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:08.014504Z","caller":"traceutil/trace.go:171","msg":"trace[826295290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:259; }","duration":"189.465505ms","start":"2026-03-05T17:43:07.824997Z","end":"2026-03-05T17:43:08.014463Z","steps":["trace[826295290] 'range keys from in-memory index tree' (duration: 189.227495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:08.286163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.261296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:43:08.286678Z","caller":"traceutil/trace.go:171","msg":"trace[458447109] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"179.560099ms","start":"2026-03-05T17:43:08.107082Z","end":"2026-03-05T17:43:08.286643Z","steps":["trace[458447109] 'process raft request' (duration: 68.682204ms)","trace[458447109] 'compare' (duration: 110.160656ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:08.286816Z","caller":"traceutil/trace.go:171","msg":"trace[458402078] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"177.231339ms","start":"2026-03-05T17:43:08.10949Z","end":"2026-03-05T17:43:08.286721Z","steps":["trace[458402078] 'process raft request' (duration: 177.197486ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:08.286849Z","caller":"traceutil/trace.go:171","msg":"trace[267717535] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"179.431998ms","start":"2026-03-05T17:43:08.107406Z","end":"2026-03-05T17:43:08.286838Z","steps":["trace[267717535] 'process raft request' (duration: 179.225901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:08.761841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.043353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:43:08.762029Z","caller":"traceutil/trace.go:171","msg":"trace[1507798386] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"405.149744ms","start":"2026-03-05T17:43:08.356851Z","end":"2026-03-05T17:43:08.762Z","steps":["trace[1507798386] 'process raft request' (duration: 49.821437ms)","trace[1507798386] 'compare' (duration: 354.802084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:08.762112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:08.356826Z","time spent":"405.260113ms","remote":"127.0.0.1:35654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:09.032027Z","caller":"traceutil/trace.go:171","msg":"trace[465162401] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"194.46144ms","start":"2026-03-05T17:43:08.837532Z","end":"2026-03-05T17:43:09.031993Z","steps":["trace[465162401] 'process raft request' (duration: 157.538594ms)","trace[465162401] 'compare' (duration: 36.749862ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:09.039106Z","caller":"traceutil/trace.go:171","msg":"trace[1947346464] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"200.140116ms","start":"2026-03-05T17:43:08.83894Z","end":"2026-03-05T17:43:09.03908Z","steps":["trace[1947346464] 'process raft request' (duration: 199.93458ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:09.203665Z","caller":"traceutil/trace.go:171","msg":"trace[42292006] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"128.025745ms","start":"2026-03-05T17:43:09.075614Z","end":"2026-03-05T17:43:09.20364Z","steps":["trace[42292006] 'process raft request' (duration: 81.533789ms)","trace[42292006] 'compare' (duration: 46.354405ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:09.206844Z","caller":"traceutil/trace.go:171","msg":"trace[550948303] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"128.814959ms","start":"2026-03-05T17:43:09.078004Z","end":"2026-03-05T17:43:09.206819Z","steps":["trace[550948303] 'process raft request' (duration: 128.679268ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.246678Z","caller":"traceutil/trace.go:171","msg":"trace[1985861362] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"376.448777ms","start":"2026-03-05T17:43:09.87018Z","end":"2026-03-05T17:43:10.246628Z","steps":["trace[1985861362] 'process raft request' (duration: 326.782798ms)","trace[1985861362] 'compare' (duration: 49.466012ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:10.246766Z","caller":"traceutil/trace.go:171","msg":"trace[457079084] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:311; }","duration":"375.75175ms","start":"2026-03-05T17:43:09.870977Z","end":"2026-03-05T17:43:10.246729Z","steps":["trace[457079084] 'read index received' (duration: 326.086301ms)","trace[457079084] 'applied index is now lower than readState.Index' (duration: 49.662359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:10.246849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.870166Z","time spent":"376.604119ms","remote":"127.0.0.1:35560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:10.246953Z","caller":"traceutil/trace.go:171","msg":"trace[1228099920] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"305.969287ms","start":"2026-03-05T17:43:09.940959Z","end":"2026-03-05T17:43:10.246928Z","steps":["trace[1228099920] 'process raft request' (duration: 305.915053ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.246986Z","caller":"traceutil/trace.go:171","msg":"trace[41656085] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"329.0964ms","start":"2026-03-05T17:43:09.917837Z","end":"2026-03-05T17:43:10.246934Z","steps":["trace[41656085] 'process raft request' (duration: 328.85168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.94094Z","time spent":"306.089967ms","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:10.247118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.125201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-05T17:43:10.247158Z","caller":"traceutil/trace.go:171","msg":"trace[992363086] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:309; }","duration":"376.227539ms","start":"2026-03-05T17:43:09.870917Z","end":"2026-03-05T17:43:10.247144Z","steps":["trace[992363086] 'agreement among raft nodes before linearized reading' (duration: 376.102158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.870893Z","time spent":"376.305136ms","remote":"127.0.0.1:35846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-05T17:43:10.247262Z","caller":"traceutil/trace.go:171","msg":"trace[1485327552] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"325.962413ms","start":"2026-03-05T17:43:09.92129Z","end":"2026-03-05T17:43:10.247252Z","steps":["trace[1485327552] 'process raft request' (duration: 325.446449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.921281Z","time spent":"326.014087ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:10.247408Z","caller":"traceutil/trace.go:171","msg":"trace[1757179263] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"375.565645ms","start":"2026-03-05T17:43:09.87183Z","end":"2026-03-05T17:43:10.247396Z","steps":["trace[1757179263] 'process raft request' (duration: 374.809252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.37222ms","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-03-05T17:43:10.247457Z","caller":"traceutil/trace.go:171","msg":"trace[1054839408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:309; }","duration":"161.452976ms","start":"2026-03-05T17:43:10.085995Z","end":"2026-03-05T17:43:10.247448Z","steps":["trace[1054839408] 'agreement among raft nodes before linearized reading' (duration: 161.392221ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.24748Z","caller":"traceutil/trace.go:171","msg":"trace[1557823112] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"309.302703ms","start":"2026-03-05T17:43:09.938168Z","end":"2026-03-05T17:43:10.247471Z","steps":["trace[1557823112] 'process raft request' (duration: 308.599524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.871821Z","time spent":"375.623339ms","remote":"127.0.0.1:35718","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-03-05T17:43:10.24759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.93815Z","time spent":"309.414942ms","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:10.247156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.917815Z","time spent":"329.269194ms","remote":"127.0.0.1:35654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:10.247686Z","caller":"traceutil/trace.go:171","msg":"trace[1940034859] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"308.420169ms","start":"2026-03-05T17:43:09.939259Z","end":"2026-03-05T17:43:10.247679Z","steps":["trace[1940034859] 'process raft request' (duration: 307.539236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.939244Z","time spent":"308.470713ms","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:10.247771Z","caller":"traceutil/trace.go:171","msg":"trace[1258906600] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"308.409179ms","start":"2026-03-05T17:43:09.939352Z","end":"2026-03-05T17:43:10.247762Z","steps":["trace[1258906600] 'process raft request' (duration: 307.471981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.939328Z","time spent":"308.479575ms","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:10.246997Z","caller":"traceutil/trace.go:171","msg":"trace[1991733426] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"376.775554ms","start":"2026-03-05T17:43:09.870207Z","end":"2026-03-05T17:43:10.246983Z","steps":["trace[1991733426] 'process raft request' (duration: 376.356319ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.247995Z","caller":"traceutil/trace.go:171","msg":"trace[708974755] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"308.31612ms","start":"2026-03-05T17:43:09.93967Z","end":"2026-03-05T17:43:10.247986Z","steps":["trace[708974755] 'process raft request' (duration: 307.178426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.247989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.870191Z","time spent":"377.774347ms","remote":"127.0.0.1:35576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:10.24801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.052682ms","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-03-05T17:43:10.248049Z","caller":"traceutil/trace.go:171","msg":"trace[1472755679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:309; }","duration":"141.14251ms","start":"2026-03-05T17:43:10.106896Z","end":"2026-03-05T17:43:10.248038Z","steps":["trace[1472755679] 'agreement among raft nodes before linearized reading' (duration: 141.069264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.248046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:09.939658Z","time spent":"308.370135ms","remote":"127.0.0.1:35644","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:10.554075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.511931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:43:10.554204Z","caller":"traceutil/trace.go:171","msg":"trace[423660168] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"219.451212ms","start":"2026-03-05T17:43:10.33473Z","end":"2026-03-05T17:43:10.554181Z","steps":["trace[423660168] 'read index received' (duration: 33.819061ms)","trace[423660168] 'applied index is now lower than readState.Index' (duration: 185.629921ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:10.554235Z","caller":"traceutil/trace.go:171","msg":"trace[1081117755] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"222.315548ms","start":"2026-03-05T17:43:10.331886Z","end":"2026-03-05T17:43:10.554201Z","steps":["trace[1081117755] 'process raft request' (duration: 36.572548ms)","trace[1081117755] 'compare' (duration: 185.112879ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:10.554503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.731475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-05T17:43:10.554549Z","caller":"traceutil/trace.go:171","msg":"trace[433662319] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:316; }","duration":"219.842534ms","start":"2026-03-05T17:43:10.334694Z","end":"2026-03-05T17:43:10.554537Z","steps":["trace[433662319] 'agreement among raft nodes before linearized reading' (duration: 219.604665ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.618433Z","caller":"traceutil/trace.go:171","msg":"trace[1258060055] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"283.359497ms","start":"2026-03-05T17:43:10.335049Z","end":"2026-03-05T17:43:10.618409Z","steps":["trace[1258060055] 'process raft request' (duration: 283.092665ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.618469Z","caller":"traceutil/trace.go:171","msg":"trace[1045574907] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"278.610605ms","start":"2026-03-05T17:43:10.339829Z","end":"2026-03-05T17:43:10.618439Z","steps":["trace[1045574907] 'process raft request' (duration: 278.55101ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.618706Z","caller":"traceutil/trace.go:171","msg":"trace[745261339] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"283.019349ms","start":"2026-03-05T17:43:10.335637Z","end":"2026-03-05T17:43:10.618656Z","steps":["trace[745261339] 'process raft request' (duration: 282.670941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:10.950715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.812444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-05T17:43:10.950832Z","caller":"traceutil/trace.go:171","msg":"trace[945483396] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"133.99592ms","start":"2026-03-05T17:43:10.816809Z","end":"2026-03-05T17:43:10.950805Z","steps":["trace[945483396] 'range keys from in-memory index tree' (duration: 133.670483ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.960713Z","caller":"traceutil/trace.go:171","msg":"trace[696888216] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"100.210911ms","start":"2026-03-05T17:43:10.860481Z","end":"2026-03-05T17:43:10.960692Z","steps":["trace[696888216] 'process raft request' (duration: 100.151547ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:10.981098Z","caller":"traceutil/trace.go:171","msg":"trace[1445275994] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"120.189269ms","start":"2026-03-05T17:43:10.860871Z","end":"2026-03-05T17:43:10.98106Z","steps":["trace[1445275994] 'process raft request' (duration: 119.994843ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:13.431192Z","caller":"traceutil/trace.go:171","msg":"trace[148535747] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"155.185277ms","start":"2026-03-05T17:43:13.275952Z","end":"2026-03-05T17:43:13.431137Z","steps":["trace[148535747] 'process raft request' (duration: 127.35622ms)","trace[148535747] 'compare' (duration: 27.547393ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:13.431273Z","caller":"traceutil/trace.go:171","msg":"trace[1465492301] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"111.760723ms","start":"2026-03-05T17:43:13.319487Z","end":"2026-03-05T17:43:13.431248Z","steps":["trace[1465492301] 'process raft request' (duration: 111.704468ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:13.431292Z","caller":"traceutil/trace.go:171","msg":"trace[250488391] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:377; }","duration":"120.091686ms","start":"2026-03-05T17:43:13.311172Z","end":"2026-03-05T17:43:13.431264Z","steps":["trace[250488391] 'read index received' (duration: 92.225397ms)","trace[250488391] 'applied index is now lower than readState.Index' (duration: 27.863449ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:13.431247Z","caller":"traceutil/trace.go:171","msg":"trace[613179460] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"152.477211ms","start":"2026-03-05T17:43:13.278736Z","end":"2026-03-05T17:43:13.431213Z","steps":["trace[613179460] 'process raft request' (duration: 152.278904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:13.431622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.556796ms","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-03-05T17:43:13.431747Z","caller":"traceutil/trace.go:171","msg":"trace[1478888888] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:367; }","duration":"114.694697ms","start":"2026-03-05T17:43:13.317028Z","end":"2026-03-05T17:43:13.431722Z","steps":["trace[1478888888] 'agreement among raft nodes before linearized reading' (duration: 114.519353ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:13.431445Z","caller":"traceutil/trace.go:171","msg":"trace[79858770] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"150.619857ms","start":"2026-03-05T17:43:13.280755Z","end":"2026-03-05T17:43:13.431375Z","steps":["trace[79858770] 'process raft request' (duration: 150.319152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:13.431533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.362086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-03-05T17:43:13.43195Z","caller":"traceutil/trace.go:171","msg":"trace[1939767292] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:367; }","duration":"153.874608ms","start":"2026-03-05T17:43:13.278057Z","end":"2026-03-05T17:43:13.431932Z","steps":["trace[1939767292] 'agreement among raft nodes before linearized reading' (duration: 153.327593ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:35.676425Z","caller":"traceutil/trace.go:171","msg":"trace[1183846524] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"106.377589ms","start":"2026-03-05T17:43:35.570025Z","end":"2026-03-05T17:43:35.676402Z","steps":["trace[1183846524] 'process raft request' (duration: 106.019188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:36.298423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.446642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:36.298493Z","caller":"traceutil/trace.go:171","msg":"trace[757046467] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:546; }","duration":"130.559691ms","start":"2026-03-05T17:43:36.167917Z","end":"2026-03-05T17:43:36.298477Z","steps":["trace[757046467] 'range keys from in-memory index tree' (duration: 130.353274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:36.919601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218279929706145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T17:43:37.419829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218279929706145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-05T17:43:37.4209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051761052s","expected-duration":"1s"} {"level":"info","ts":"2026-03-05T17:43:37.421203Z","caller":"traceutil/trace.go:171","msg":"trace[323321706] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.052171697s","start":"2026-03-05T17:43:36.36901Z","end":"2026-03-05T17:43:37.421182Z","steps":["trace[323321706] 'process raft request' (duration: 1.052041155s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.421337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.368988Z","time spent":"1.052270285s","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:37.430591Z","caller":"traceutil/trace.go:171","msg":"trace[1270363537] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:565; }","duration":"1.011575454s","start":"2026-03-05T17:43:36.419Z","end":"2026-03-05T17:43:37.430576Z","steps":["trace[1270363537] 'read index received' (duration: 1.002090738s)","trace[1270363537] 'applied index is now lower than readState.Index' (duration: 9.484195ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:37.430662Z","caller":"traceutil/trace.go:171","msg":"trace[114131196] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"224.037746ms","start":"2026-03-05T17:43:37.206615Z","end":"2026-03-05T17:43:37.430652Z","steps":["trace[114131196] 'process raft request' (duration: 223.921346ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:37.430714Z","caller":"traceutil/trace.go:171","msg":"trace[694785979] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"861.711793ms","start":"2026-03-05T17:43:36.568975Z","end":"2026-03-05T17:43:37.430687Z","steps":["trace[694785979] 'process raft request' (duration: 861.443719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.430793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.568939Z","time spent":"861.823864ms","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-05T17:43:37.43107Z","caller":"traceutil/trace.go:171","msg":"trace[1771136712] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"657.906459ms","start":"2026-03-05T17:43:36.773016Z","end":"2026-03-05T17:43:37.430922Z","steps":["trace[1771136712] 'process raft request' (duration: 657.489361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.431182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.772981Z","time spent":"658.140849ms","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-05T17:43:37.431725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012687639s","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-03-05T17:43:37.431845Z","caller":"traceutil/trace.go:171","msg":"trace[1803893332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"1.012847863s","start":"2026-03-05T17:43:36.418968Z","end":"2026-03-05T17:43:37.431816Z","steps":["trace[1803893332] 'agreement among raft nodes before linearized reading' (duration: 1.011804963s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.431906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.418952Z","time spent":"1.012944992s","remote":"127.0.0.1:35718","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-03-05T17:43:37.440431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006698179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-05T17:43:37.440513Z","caller":"traceutil/trace.go:171","msg":"trace[1144634520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"1.00682168s","start":"2026-03-05T17:43:36.433676Z","end":"2026-03-05T17:43:37.440498Z","steps":["trace[1144634520] 'agreement among raft nodes before linearized reading' (duration: 1.006675257s)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.440551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.433662Z","time spent":"1.006882655s","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T17:43:37.440615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.514884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-05T17:43:37.440688Z","caller":"traceutil/trace.go:171","msg":"trace[1431419145] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:551; }","duration":"667.651726ms","start":"2026-03-05T17:43:36.773013Z","end":"2026-03-05T17:43:37.440665Z","steps":["trace[1431419145] 'agreement among raft nodes before linearized reading' (duration: 667.483401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.440738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.772999Z","time spent":"667.729102ms","remote":"127.0.0.1:35510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-03-05T17:43:37.440781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.244897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T17:43:37.44081Z","caller":"traceutil/trace.go:171","msg":"trace[1179579629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"684.308723ms","start":"2026-03-05T17:43:36.756495Z","end":"2026-03-05T17:43:37.440804Z","steps":["trace[1179579629] 'agreement among raft nodes before linearized reading' (duration: 684.259878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.440835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.756475Z","time spent":"684.355857ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T17:43:37.44045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.306731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-05T17:43:37.440928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.376069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:37.440942Z","caller":"traceutil/trace.go:171","msg":"trace[857640427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"306.87466ms","start":"2026-03-05T17:43:37.13406Z","end":"2026-03-05T17:43:37.440934Z","steps":["trace[857640427] 'agreement among raft nodes before linearized reading' (duration: 306.305541ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:37.440986Z","caller":"traceutil/trace.go:171","msg":"trace[31709121] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:551; }","duration":"473.458456ms","start":"2026-03-05T17:43:36.967505Z","end":"2026-03-05T17:43:37.440963Z","steps":["trace[31709121] 'agreement among raft nodes before linearized reading' (duration: 473.394581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:37.441004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:37.134018Z","time spent":"306.978429ms","remote":"127.0.0.1:35460","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-03-05T17:43:37.441019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:36.967485Z","time spent":"473.526673ms","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-05T17:43:37.441327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.799093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-05T17:43:37.441363Z","caller":"traceutil/trace.go:171","msg":"trace[1640761853] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:551; }","duration":"273.87135ms","start":"2026-03-05T17:43:37.16748Z","end":"2026-03-05T17:43:37.441351Z","steps":["trace[1640761853] 'agreement among raft nodes before linearized reading' (duration: 273.825206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:41.962178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218279929706260,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-05T17:43:42.128001Z","caller":"traceutil/trace.go:171","msg":"trace[609117418] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"666.550601ms","start":"2026-03-05T17:43:41.461421Z","end":"2026-03-05T17:43:42.127972Z","steps":["trace[609117418] 'read index received' (duration: 666.319855ms)","trace[609117418] 'applied index is now lower than readState.Index' (duration: 229.966µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:42.128305Z","caller":"traceutil/trace.go:171","msg":"trace[1407787096] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"668.51985ms","start":"2026-03-05T17:43:41.459769Z","end":"2026-03-05T17:43:42.128289Z","steps":["trace[1407787096] 'process raft request' (duration: 668.073256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:42.128457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:41.459749Z","time spent":"668.610974ms","remote":"127.0.0.1:35632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4877,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T17:43:42.128848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.428189ms","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-03-05T17:43:42.1289Z","caller":"traceutil/trace.go:171","msg":"trace[840441434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"667.542152ms","start":"2026-03-05T17:43:41.461346Z","end":"2026-03-05T17:43:42.128889Z","steps":["trace[840441434] 'agreement among raft nodes before linearized reading' (duration: 667.394631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:42.128941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:41.46131Z","time spent":"667.621408ms","remote":"127.0.0.1:35718","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-03-05T17:43:42.129186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.900041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-05T17:43:42.129228Z","caller":"traceutil/trace.go:171","msg":"trace[847497347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"662.962517ms","start":"2026-03-05T17:43:41.466256Z","end":"2026-03-05T17:43:42.129218Z","steps":["trace[847497347] 'agreement among raft nodes before linearized reading' (duration: 662.862933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:42.129264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:41.466241Z","time spent":"663.010824ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-05T17:43:42.129598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.395914ms","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-03-05T17:43:42.129642Z","caller":"traceutil/trace.go:171","msg":"trace[1531054787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"422.47014ms","start":"2026-03-05T17:43:41.707161Z","end":"2026-03-05T17:43:42.129631Z","steps":["trace[1531054787] 'agreement among raft nodes before linearized reading' (duration: 422.364416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:42.129681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:41.707143Z","time spent":"422.528246ms","remote":"127.0.0.1:35718","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-03-05T17:43:42.13001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.735206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-05T17:43:42.130059Z","caller":"traceutil/trace.go:171","msg":"trace[1407328705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"651.807042ms","start":"2026-03-05T17:43:41.478242Z","end":"2026-03-05T17:43:42.130049Z","steps":["trace[1407328705] 'agreement among raft nodes before linearized reading' (duration: 651.653792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:42.130092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:41.478224Z","time spent":"651.859289ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-05T17:43:42.130344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.499877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-05T17:43:42.130391Z","caller":"traceutil/trace.go:171","msg":"trace[701125598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"662.568103ms","start":"2026-03-05T17:43:41.467809Z","end":"2026-03-05T17:43:42.130377Z","steps":["trace[701125598] 'agreement among raft nodes before linearized reading' (duration: 662.46098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:42.130428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:41.467795Z","time spent":"662.62319ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-05T17:43:45.143196Z","caller":"traceutil/trace.go:171","msg":"trace[1052484345] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"179.997018ms","start":"2026-03-05T17:43:44.963169Z","end":"2026-03-05T17:43:45.143166Z","steps":["trace[1052484345] 'process raft request' (duration: 179.689695ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:46.372294Z","caller":"traceutil/trace.go:171","msg":"trace[1267516742] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"214.567852ms","start":"2026-03-05T17:43:46.157697Z","end":"2026-03-05T17:43:46.372265Z","steps":["trace[1267516742] 'read index received' (duration: 214.283927ms)","trace[1267516742] 'applied index is now lower than readState.Index' (duration: 282.895µs)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:46.372609Z","caller":"traceutil/trace.go:171","msg":"trace[1115549944] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"216.184188ms","start":"2026-03-05T17:43:46.15637Z","end":"2026-03-05T17:43:46.372555Z","steps":["trace[1115549944] 'process raft request' (duration: 215.716023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:46.372848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.980333ms","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":"warn","ts":"2026-03-05T17:43:46.372881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.209171ms","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-03-05T17:43:46.372923Z","caller":"traceutil/trace.go:171","msg":"trace[1653786067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"209.072979ms","start":"2026-03-05T17:43:46.163833Z","end":"2026-03-05T17:43:46.372906Z","steps":["trace[1653786067] 'agreement among raft nodes before linearized reading' (duration: 208.86033ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:46.372936Z","caller":"traceutil/trace.go:171","msg":"trace[1029798091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"204.281547ms","start":"2026-03-05T17:43:46.168634Z","end":"2026-03-05T17:43:46.372916Z","steps":["trace[1029798091] 'agreement among raft nodes before linearized reading' (duration: 204.137395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:46.373486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.159015ms","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-03-05T17:43:46.373515Z","caller":"traceutil/trace.go:171","msg":"trace[1049136965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"208.207242ms","start":"2026-03-05T17:43:46.1653Z","end":"2026-03-05T17:43:46.373507Z","steps":["trace[1049136965] 'agreement among raft nodes before linearized reading' (duration: 207.607944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:46.373572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.861195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-05T17:43:46.373626Z","caller":"traceutil/trace.go:171","msg":"trace[1244264155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"215.938911ms","start":"2026-03-05T17:43:46.157674Z","end":"2026-03-05T17:43:46.373613Z","steps":["trace[1244264155] 'agreement among raft nodes before linearized reading' (duration: 215.38729ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:47.129698Z","caller":"traceutil/trace.go:171","msg":"trace[330155913] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"752.644643ms","start":"2026-03-05T17:43:46.377024Z","end":"2026-03-05T17:43:47.129669Z","steps":["trace[330155913] 'process raft request' (duration: 752.485181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:47.129871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:46.377003Z","time spent":"752.766627ms","remote":"127.0.0.1:35718","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-03-05T17:43:47.213645Z","caller":"traceutil/trace.go:171","msg":"trace[683440586] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"836.553102ms","start":"2026-03-05T17:43:46.377066Z","end":"2026-03-05T17:43:47.21362Z","steps":["trace[683440586] 'process raft request' (duration: 808.111177ms)","trace[683440586] 'compare' (duration: 28.192628ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:43:47.213724Z","caller":"traceutil/trace.go:171","msg":"trace[1367632430] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:638; }","duration":"440.319967ms","start":"2026-03-05T17:43:46.77338Z","end":"2026-03-05T17:43:47.2137Z","steps":["trace[1367632430] 'read index received' (duration: 356.345521ms)","trace[1367632430] 'applied index is now lower than readState.Index' (duration: 83.973236ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:43:47.213764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:46.377039Z","time spent":"836.661987ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T17:43:47.213765Z","caller":"traceutil/trace.go:171","msg":"trace[842298241] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"836.444458ms","start":"2026-03-05T17:43:46.377302Z","end":"2026-03-05T17:43:47.213747Z","steps":["trace[842298241] 'process raft request' (duration: 836.345193ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:43:47.213783Z","caller":"traceutil/trace.go:171","msg":"trace[2124489027] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"836.639359ms","start":"2026-03-05T17:43:46.377099Z","end":"2026-03-05T17:43:47.213739Z","steps":["trace[2124489027] 'process raft request' (duration: 836.479207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:47.213853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:46.377292Z","time spent":"836.514695ms","remote":"127.0.0.1:35718","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-03-05T17:43:47.213988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.609143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-05T17:43:47.214043Z","caller":"traceutil/trace.go:171","msg":"trace[449336655] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:620; }","duration":"440.700828ms","start":"2026-03-05T17:43:46.773325Z","end":"2026-03-05T17:43:47.214026Z","steps":["trace[449336655] 'agreement among raft nodes before linearized reading' (duration: 440.506728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:43:47.214461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:46.773298Z","time spent":"441.087639ms","remote":"127.0.0.1:35510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-03-05T17:43:47.213981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:43:46.377077Z","time spent":"836.791351ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-05T17:44:07.353494Z","caller":"traceutil/trace.go:171","msg":"trace[1529926980] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"116.668047ms","start":"2026-03-05T17:44:07.236803Z","end":"2026-03-05T17:44:07.353472Z","steps":["trace[1529926980] 'process raft request' (duration: 116.48263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-05T17:45:07.985452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.523853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:45:07.98587Z","caller":"traceutil/trace.go:171","msg":"trace[1277978775] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"318.77684ms","start":"2026-03-05T17:45:07.66707Z","end":"2026-03-05T17:45:07.985847Z","steps":["trace[1277978775] 'process raft request' (duration: 69.749397ms)","trace[1277978775] 'compare' (duration: 248.043895ms)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:45:07.985977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-05T17:45:07.667054Z","time spent":"318.878633ms","remote":"127.0.0.1:35718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-05T17:45:15.881209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.225677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-05T17:45:15.881845Z","caller":"traceutil/trace.go:171","msg":"trace[92744768] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"154.318079ms","start":"2026-03-05T17:45:15.727501Z","end":"2026-03-05T17:45:15.881819Z","steps":["trace[92744768] 'process raft request' (duration: 45.381092ms)","trace[92744768] 'compare' (duration: 108.101152ms)"],"step_count":2} {"level":"info","ts":"2026-03-05T17:45:16.942742Z","caller":"traceutil/trace.go:171","msg":"trace[149132822] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"146.316183ms","start":"2026-03-05T17:45:16.796402Z","end":"2026-03-05T17:45:16.942718Z","steps":["trace[149132822] 'read index received' (duration: 146.210448ms)","trace[149132822] 'applied index is now lower than readState.Index' (duration: 104.305µs)"],"step_count":2} {"level":"warn","ts":"2026-03-05T17:45:16.942992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.572074ms","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-03-05T17:45:16.943044Z","caller":"traceutil/trace.go:171","msg":"trace[328203256] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"146.684148ms","start":"2026-03-05T17:45:16.796348Z","end":"2026-03-05T17:45:16.943032Z","steps":["trace[328203256] 'agreement among raft nodes before linearized reading' (duration: 146.473639ms)"],"step_count":1} {"level":"info","ts":"2026-03-05T17:45:17.054924Z","caller":"traceutil/trace.go:171","msg":"trace[1093026460] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"107.851206ms","start":"2026-03-05T17:45:16.947042Z","end":"2026-03-05T17:45:17.054893Z","steps":["trace[1093026460] 'process raft request' (duration: 97.369856ms)","trace[1093026460] 'compare' (duration: 10.054022ms)"],"step_count":2}