{"level":"warn","ts":"2026-05-21T03:30:10.440186Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:30:10.440268Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-05-21T03:30:10.440325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:30:10.440336Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-21T03:30:10.440358Z","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-05-21T03:30:10.440892Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-05-21T03:30:10.440969Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-05-21T03:30:10.445434Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.234903ms"} {"level":"info","ts":"2026-05-21T03:30:10.453433Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-05-21T03:30:10.453502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T03:30:10.453528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-05-21T03:30:10.453546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T03:30:10.453558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-05-21T03:30:10.453599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-05-21T03:30:10.460778Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T03:30:10.46224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T03:30:10.463353Z","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-05-21T03:30:10.465824Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T03:30:10.4662Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T03:30:10.466411Z","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-05-21T03:30:10.466504Z","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-05-21T03:30:10.466513Z","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-05-21T03:30:10.467226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-05-21T03:30:10.467392Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-21T03:30:10.476564Z","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-05-21T03:30:10.476691Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-21T03:30:10.476731Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-21T03:30:10.477035Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T03:30:10.47709Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T03:30:10.554781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T03:30:10.554877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T03:30:10.554959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-05-21T03:30:10.554994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-05-21T03:30:10.555042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-21T03:30:10.555068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-05-21T03:30:10.555082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-21T03:30:10.55576Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:30:10.557027Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:30:10.557131Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:30:10.55716Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:30:10.557194Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T03:30:10.557343Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:30:10.557438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:30:10.560691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-05-21T03:30:10.560819Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T03:30:10.560858Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T03:30:10.562298Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T03:30:12.822229Z","caller":"traceutil/trace.go:171","msg":"trace[48778499] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"155.625808ms","start":"2026-05-21T03:30:12.666571Z","end":"2026-05-21T03:30:12.822197Z","steps":["trace[48778499] 'read index received' (duration: 155.28902ms)","trace[48778499] 'applied index is now lower than readState.Index' (duration: 335.658µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:12.822275Z","caller":"traceutil/trace.go:171","msg":"trace[1574869134] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"156.701465ms","start":"2026-05-21T03:30:12.66553Z","end":"2026-05-21T03:30:12.822231Z","steps":["trace[1574869134] 'process raft request' (duration: 156.278965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:12.822657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.009818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:30:12.822775Z","caller":"traceutil/trace.go:171","msg":"trace[951102497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:2; }","duration":"156.191803ms","start":"2026-05-21T03:30:12.666559Z","end":"2026-05-21T03:30:12.822751Z","steps":["trace[951102497] 'agreement among raft nodes before linearized reading' (duration: 155.759382ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.903838Z","caller":"traceutil/trace.go:171","msg":"trace[188950570] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"159.414321ms","start":"2026-05-21T03:30:12.744385Z","end":"2026-05-21T03:30:12.903799Z","steps":["trace[188950570] 'process raft request' (duration: 158.662922ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.903929Z","caller":"traceutil/trace.go:171","msg":"trace[22041821] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"158.640212ms","start":"2026-05-21T03:30:12.745264Z","end":"2026-05-21T03:30:12.903904Z","steps":["trace[22041821] 'process raft request' (duration: 158.520779ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.903932Z","caller":"traceutil/trace.go:171","msg":"trace[1792093142] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"159.453652ms","start":"2026-05-21T03:30:12.744445Z","end":"2026-05-21T03:30:12.903899Z","steps":["trace[1792093142] 'process raft request' (duration: 159.162015ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.904076Z","caller":"traceutil/trace.go:171","msg":"trace[2052492563] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"159.266178ms","start":"2026-05-21T03:30:12.744785Z","end":"2026-05-21T03:30:12.904051Z","steps":["trace[2052492563] 'process raft request' (duration: 158.93527ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.904103Z","caller":"traceutil/trace.go:171","msg":"trace[457968208] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"159.156875ms","start":"2026-05-21T03:30:12.744935Z","end":"2026-05-21T03:30:12.904092Z","steps":["trace[457968208] 'process raft request' (duration: 158.816056ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.904171Z","caller":"traceutil/trace.go:171","msg":"trace[1434398690] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"142.370775ms","start":"2026-05-21T03:30:12.761784Z","end":"2026-05-21T03:30:12.904155Z","steps":["trace[1434398690] 'process raft request' (duration: 142.066698ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.90408Z","caller":"traceutil/trace.go:171","msg":"trace[354670528] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"159.436892ms","start":"2026-05-21T03:30:12.744418Z","end":"2026-05-21T03:30:12.903855Z","steps":["trace[354670528] 'process raft request' (duration: 159.271308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:12.904441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.137242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T03:30:12.904479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.993761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:30:12.9045Z","caller":"traceutil/trace.go:171","msg":"trace[2028312098] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"161.268296ms","start":"2026-05-21T03:30:12.743218Z","end":"2026-05-21T03:30:12.904486Z","steps":["trace[2028312098] 'agreement among raft nodes before linearized reading' (duration: 161.143033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:12.904481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.626503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:30:12.904568Z","caller":"traceutil/trace.go:171","msg":"trace[705901662] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"181.817547ms","start":"2026-05-21T03:30:12.722737Z","end":"2026-05-21T03:30:12.904554Z","steps":["trace[705901662] 'agreement among raft nodes before linearized reading' (duration: 181.503459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:12.904449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.21527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-21T03:30:12.904602Z","caller":"traceutil/trace.go:171","msg":"trace[500521351] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"159.066163ms","start":"2026-05-21T03:30:12.745443Z","end":"2026-05-21T03:30:12.90451Z","steps":["trace[500521351] 'agreement among raft nodes before linearized reading' (duration: 158.857488ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.904681Z","caller":"traceutil/trace.go:171","msg":"trace[637185394] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9; }","duration":"237.469576ms","start":"2026-05-21T03:30:12.667202Z","end":"2026-05-21T03:30:12.904671Z","steps":["trace[637185394] 'agreement among raft nodes before linearized reading' (duration: 237.192549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:12.904698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.233156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-21T03:30:12.904739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.979708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:30:12.904751Z","caller":"traceutil/trace.go:171","msg":"trace[339699865] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"109.311378ms","start":"2026-05-21T03:30:12.795421Z","end":"2026-05-21T03:30:12.904733Z","steps":["trace[339699865] 'agreement among raft nodes before linearized reading' (duration: 109.211316ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:12.904791Z","caller":"traceutil/trace.go:171","msg":"trace[965177542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:9; }","duration":"238.036689ms","start":"2026-05-21T03:30:12.66674Z","end":"2026-05-21T03:30:12.904777Z","steps":["trace[965177542] 'agreement among raft nodes before linearized reading' (duration: 237.973108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:12.904897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.233464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:30:12.904939Z","caller":"traceutil/trace.go:171","msg":"trace[1083667383] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:9; }","duration":"238.276175ms","start":"2026-05-21T03:30:12.666654Z","end":"2026-05-21T03:30:12.90493Z","steps":["trace[1083667383] 'agreement among raft nodes before linearized reading' (duration: 238.215684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:12.90494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.056419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:30:12.905012Z","caller":"traceutil/trace.go:171","msg":"trace[419189949] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:0; response_revision:9; }","duration":"163.130142ms","start":"2026-05-21T03:30:12.74187Z","end":"2026-05-21T03:30:12.905Z","steps":["trace[419189949] 'agreement among raft nodes before linearized reading' (duration: 163.030969ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.050695Z","caller":"traceutil/trace.go:171","msg":"trace[392469437] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"433.338276ms","start":"2026-05-21T03:30:20.617325Z","end":"2026-05-21T03:30:21.050664Z","steps":["trace[392469437] 'read index received' (duration: 418.832662ms)","trace[392469437] 'applied index is now lower than readState.Index' (duration: 14.503894ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:21.050899Z","caller":"traceutil/trace.go:171","msg":"trace[796284959] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"435.439997ms","start":"2026-05-21T03:30:20.615444Z","end":"2026-05-21T03:30:21.050884Z","steps":["trace[796284959] 'process raft request' (duration: 420.81267ms)","trace[796284959] 'compare' (duration: 14.214507ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:30:21.051189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.668519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-21T03:30:21.0513Z","caller":"traceutil/trace.go:171","msg":"trace[434422397] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"382.833123ms","start":"2026-05-21T03:30:20.66844Z","end":"2026-05-21T03:30:21.051273Z","steps":["trace[434422397] 'agreement among raft nodes before linearized reading' (duration: 382.608487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:21.051354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:20.668413Z","time spent":"382.931196ms","remote":"127.0.0.1:39236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-21T03:30:21.051245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.809452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-21T03:30:21.051525Z","caller":"traceutil/trace.go:171","msg":"trace[161832945] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"183.15903ms","start":"2026-05-21T03:30:20.868334Z","end":"2026-05-21T03:30:21.051493Z","steps":["trace[161832945] 'agreement among raft nodes before linearized reading' (duration: 182.719139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:21.051588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.529799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-21T03:30:21.051675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.9486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-21T03:30:21.051673Z","caller":"traceutil/trace.go:171","msg":"trace[704401921] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:348; }","duration":"233.57994ms","start":"2026-05-21T03:30:20.81804Z","end":"2026-05-21T03:30:21.05162Z","steps":["trace[704401921] 'agreement among raft nodes before linearized reading' (duration: 233.522959ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.05172Z","caller":"traceutil/trace.go:171","msg":"trace[732699908] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:348; }","duration":"282.025632ms","start":"2026-05-21T03:30:20.769682Z","end":"2026-05-21T03:30:21.051707Z","steps":["trace[732699908] 'agreement among raft nodes before linearized reading' (duration: 281.92294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:21.051871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.971295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-21T03:30:21.051905Z","caller":"traceutil/trace.go:171","msg":"trace[1223789533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"333.041607ms","start":"2026-05-21T03:30:20.718855Z","end":"2026-05-21T03:30:21.051896Z","steps":["trace[1223789533] 'agreement among raft nodes before linearized reading' (duration: 332.985446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:21.051932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:20.71883Z","time spent":"333.096489ms","remote":"127.0.0.1:39236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-21T03:30:21.052006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.671848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-21T03:30:21.052084Z","caller":"traceutil/trace.go:171","msg":"trace[2041054982] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:348; }","duration":"434.7782ms","start":"2026-05-21T03:30:20.617292Z","end":"2026-05-21T03:30:21.05207Z","steps":["trace[2041054982] 'agreement among raft nodes before linearized reading' (duration: 434.583656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:21.051538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.522524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T03:30:21.052153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:20.617276Z","time spent":"434.863773ms","remote":"127.0.0.1:39236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-05-21T03:30:21.052256Z","caller":"traceutil/trace.go:171","msg":"trace[1259016776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"235.212391ms","start":"2026-05-21T03:30:20.816957Z","end":"2026-05-21T03:30:21.052169Z","steps":["trace[1259016776] 'agreement among raft nodes before linearized reading' (duration: 234.523184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:21.051428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:20.61541Z","time spent":"435.519668ms","remote":"127.0.0.1:39522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:30:21.052435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.00188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-21T03:30:21.052533Z","caller":"traceutil/trace.go:171","msg":"trace[781086443] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:348; }","duration":"134.136264ms","start":"2026-05-21T03:30:20.918374Z","end":"2026-05-21T03:30:21.05251Z","steps":["trace[781086443] 'agreement among raft nodes before linearized reading' (duration: 133.667963ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.207734Z","caller":"traceutil/trace.go:171","msg":"trace[1700874134] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"149.045108ms","start":"2026-05-21T03:30:21.058647Z","end":"2026-05-21T03:30:21.207692Z","steps":["trace[1700874134] 'process raft request' (duration: 148.689199ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.207799Z","caller":"traceutil/trace.go:171","msg":"trace[1947339633] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"146.316692ms","start":"2026-05-21T03:30:21.061423Z","end":"2026-05-21T03:30:21.20774Z","steps":["trace[1947339633] 'process raft request' (duration: 146.133377ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.207736Z","caller":"traceutil/trace.go:171","msg":"trace[673001068] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"146.406644ms","start":"2026-05-21T03:30:21.061292Z","end":"2026-05-21T03:30:21.207699Z","steps":["trace[673001068] 'process raft request' (duration: 146.202159ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.207995Z","caller":"traceutil/trace.go:171","msg":"trace[579674697] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"141.849902ms","start":"2026-05-21T03:30:21.066114Z","end":"2026-05-21T03:30:21.207964Z","steps":["trace[579674697] 'process raft request' (duration: 141.808601ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.208302Z","caller":"traceutil/trace.go:171","msg":"trace[351945664] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"145.777028ms","start":"2026-05-21T03:30:21.062516Z","end":"2026-05-21T03:30:21.208294Z","steps":["trace[351945664] 'process raft request' (duration: 145.226295ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.208306Z","caller":"traceutil/trace.go:171","msg":"trace[888092807] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"144.430775ms","start":"2026-05-21T03:30:21.063864Z","end":"2026-05-21T03:30:21.208295Z","steps":["trace[888092807] 'process raft request' (duration: 143.924033ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.208353Z","caller":"traceutil/trace.go:171","msg":"trace[1242709845] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"146.901265ms","start":"2026-05-21T03:30:21.061438Z","end":"2026-05-21T03:30:21.208339Z","steps":["trace[1242709845] 'process raft request' (duration: 146.163407ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.208447Z","caller":"traceutil/trace.go:171","msg":"trace[1228973374] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"146.074446ms","start":"2026-05-21T03:30:21.062362Z","end":"2026-05-21T03:30:21.208437Z","steps":["trace[1228973374] 'process raft request' (duration: 145.326147ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.208495Z","caller":"traceutil/trace.go:171","msg":"trace[1492047609] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"143.544594ms","start":"2026-05-21T03:30:21.064939Z","end":"2026-05-21T03:30:21.208484Z","steps":["trace[1492047609] 'process raft request' (duration: 142.881098ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.208526Z","caller":"traceutil/trace.go:171","msg":"trace[1698175745] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"142.679773ms","start":"2026-05-21T03:30:21.06584Z","end":"2026-05-21T03:30:21.20852Z","steps":["trace[1698175745] 'process raft request' (duration: 142.055367ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:21.208704Z","caller":"traceutil/trace.go:171","msg":"trace[343002501] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"142.957659ms","start":"2026-05-21T03:30:21.06574Z","end":"2026-05-21T03:30:21.208698Z","steps":["trace[343002501] 'process raft request' (duration: 142.117869ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:27.245849Z","caller":"traceutil/trace.go:171","msg":"trace[79804588] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"117.441756ms","start":"2026-05-21T03:30:27.128387Z","end":"2026-05-21T03:30:27.245828Z","steps":["trace[79804588] 'process raft request' (duration: 117.300933ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:27.28809Z","caller":"traceutil/trace.go:171","msg":"trace[822936093] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"151.253401ms","start":"2026-05-21T03:30:27.136813Z","end":"2026-05-21T03:30:27.288067Z","steps":["trace[822936093] 'process raft request' (duration: 130.987687ms)","trace[822936093] 'compare' (duration: 19.995668ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:33.494018Z","caller":"traceutil/trace.go:171","msg":"trace[476964054] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"123.31528ms","start":"2026-05-21T03:30:33.370666Z","end":"2026-05-21T03:30:33.493982Z","steps":["trace[476964054] 'process raft request' (duration: 105.725071ms)","trace[476964054] 'compare' (duration: 17.442405ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:33.542763Z","caller":"traceutil/trace.go:171","msg":"trace[152474363] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"164.078235ms","start":"2026-05-21T03:30:33.378652Z","end":"2026-05-21T03:30:33.54273Z","steps":["trace[152474363] 'process raft request' (duration: 163.87312ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:35.639465Z","caller":"traceutil/trace.go:171","msg":"trace[599654745] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"140.879648ms","start":"2026-05-21T03:30:35.498559Z","end":"2026-05-21T03:30:35.639439Z","steps":["trace[599654745] 'read index received' (duration: 140.669533ms)","trace[599654745] 'applied index is now lower than readState.Index' (duration: 208.965µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:30:35.639653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.044982ms","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-05-21T03:30:35.639719Z","caller":"traceutil/trace.go:171","msg":"trace[1903401438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"141.172625ms","start":"2026-05-21T03:30:35.498516Z","end":"2026-05-21T03:30:35.639689Z","steps":["trace[1903401438] 'agreement among raft nodes before linearized reading' (duration: 141.026862ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:35.639799Z","caller":"traceutil/trace.go:171","msg":"trace[1218988287] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"264.861914ms","start":"2026-05-21T03:30:35.374906Z","end":"2026-05-21T03:30:35.639768Z","steps":["trace[1218988287] 'process raft request' (duration: 264.394823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:43.95097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.4145ms","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-05-21T03:30:43.951056Z","caller":"traceutil/trace.go:171","msg":"trace[1328072849] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"102.939592ms","start":"2026-05-21T03:30:43.848093Z","end":"2026-05-21T03:30:43.951033Z","steps":["trace[1328072849] 'read index received' (duration: 52.480431ms)","trace[1328072849] 'applied index is now lower than readState.Index' (duration: 50.458051ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:43.951093Z","caller":"traceutil/trace.go:171","msg":"trace[2032752169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"161.638765ms","start":"2026-05-21T03:30:43.789425Z","end":"2026-05-21T03:30:43.951064Z","steps":["trace[2032752169] 'agreement among raft nodes before linearized reading' (duration: 58.759474ms)","trace[2032752169] 'range keys from in-memory index tree' (duration: 102.629775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:30:43.9512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.649836ms","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-05-21T03:30:43.951257Z","caller":"traceutil/trace.go:171","msg":"trace[75491451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"136.764059ms","start":"2026-05-21T03:30:43.81448Z","end":"2026-05-21T03:30:43.951244Z","steps":["trace[75491451] 'agreement among raft nodes before linearized reading' (duration: 136.638856ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:43.951272Z","caller":"traceutil/trace.go:171","msg":"trace[1464549734] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"129.959802ms","start":"2026-05-21T03:30:43.82122Z","end":"2026-05-21T03:30:43.95118Z","steps":["trace[1464549734] 'process raft request' (duration: 79.339686ms)","trace[1464549734] 'compare' (duration: 50.37045ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:48.655457Z","caller":"traceutil/trace.go:171","msg":"trace[815554746] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"107.531005ms","start":"2026-05-21T03:30:48.547905Z","end":"2026-05-21T03:30:48.655436Z","steps":["trace[815554746] 'process raft request' (duration: 99.673803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:49.901894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.480939ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:30:49.902026Z","caller":"traceutil/trace.go:171","msg":"trace[1769621101] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:587; }","duration":"612.619262ms","start":"2026-05-21T03:30:49.289386Z","end":"2026-05-21T03:30:49.902005Z","steps":["trace[1769621101] 'range keys from in-memory index tree' (duration: 612.466978ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:49.902042Z","caller":"traceutil/trace.go:171","msg":"trace[1887855219] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"915.663838ms","start":"2026-05-21T03:30:48.986356Z","end":"2026-05-21T03:30:49.902019Z","steps":["trace[1887855219] 'process raft request' (duration: 911.827384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:49.902779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:48.986325Z","time spent":"916.12948ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:30:49.906947Z","caller":"traceutil/trace.go:171","msg":"trace[150627529] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"870.913986ms","start":"2026-05-21T03:30:49.035995Z","end":"2026-05-21T03:30:49.906909Z","steps":["trace[150627529] 'process raft request' (duration: 870.558618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:49.907112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:49.035956Z","time spent":"871.06453ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:30:49.907688Z","caller":"traceutil/trace.go:171","msg":"trace[401945976] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"695.258859ms","start":"2026-05-21T03:30:49.212416Z","end":"2026-05-21T03:30:49.907675Z","steps":["trace[401945976] 'process raft request' (duration: 694.653994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:49.907819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:49.21234Z","time spent":"695.383952ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:30:54.997163Z","caller":"traceutil/trace.go:171","msg":"trace[863890502] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"122.734646ms","start":"2026-05-21T03:30:54.874402Z","end":"2026-05-21T03:30:54.997137Z","steps":["trace[863890502] 'process raft request' (duration: 120.556962ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:55.654211Z","caller":"traceutil/trace.go:171","msg":"trace[891105218] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"179.845329ms","start":"2026-05-21T03:30:55.474337Z","end":"2026-05-21T03:30:55.654182Z","steps":["trace[891105218] 'process raft request' (duration: 179.190713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:56.738883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373077627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:30:57.239235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373077627,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:30:57.369505Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.207541783s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T03:30:58.270654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.314317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:30:58.271355Z","caller":"traceutil/trace.go:171","msg":"trace[871967938] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"2.112374696s","start":"2026-05-21T03:30:56.158952Z","end":"2026-05-21T03:30:58.271327Z","steps":["trace[871967938] 'process raft request' (duration: 1.211720744s)","trace[871967938] 'compare' (duration: 892.994025ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:30:58.271467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.033606403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T03:30:58.271377Z","caller":"traceutil/trace.go:171","msg":"trace[2054942931] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"2.03348222s","start":"2026-05-21T03:30:56.237856Z","end":"2026-05-21T03:30:58.271339Z","steps":["trace[2054942931] 'read index received' (duration: 1.132036549s)","trace[2054942931] 'applied index is now lower than readState.Index' (duration: 901.444711ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:30:58.271506Z","caller":"traceutil/trace.go:171","msg":"trace[1637109753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"2.033683195s","start":"2026-05-21T03:30:56.237812Z","end":"2026-05-21T03:30:58.271495Z","steps":["trace[1637109753] 'agreement among raft nodes before linearized reading' (duration: 2.033584553s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:58.271554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:56.237791Z","time spent":"2.033754327s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T03:30:58.271616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.994282114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-52sv8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-21T03:30:58.271678Z","caller":"traceutil/trace.go:171","msg":"trace[108026540] range","detail":"{range_begin:/registry/pods/kube-system/cilium-52sv8; range_end:; response_count:1; response_revision:633; }","duration":"1.994372075s","start":"2026-05-21T03:30:56.277293Z","end":"2026-05-21T03:30:58.271666Z","steps":["trace[108026540] 'agreement among raft nodes before linearized reading' (duration: 1.994257822s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:58.271712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:56.277275Z","time spent":"1.994428537s","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-52sv8\" "} {"level":"info","ts":"2026-05-21T03:30:58.271571Z","caller":"traceutil/trace.go:171","msg":"trace[604486265] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.230431741s","start":"2026-05-21T03:30:57.041056Z","end":"2026-05-21T03:30:58.271488Z","steps":["trace[604486265] 'process raft request' (duration: 1.230203865s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:58.271771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.056202ms","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-05-21T03:30:58.27183Z","caller":"traceutil/trace.go:171","msg":"trace[1801434138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"177.177895ms","start":"2026-05-21T03:30:58.094639Z","end":"2026-05-21T03:30:58.271817Z","steps":["trace[1801434138] 'agreement among raft nodes before linearized reading' (duration: 176.97916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:58.271849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.939752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:30:58.271889Z","caller":"traceutil/trace.go:171","msg":"trace[21286101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"584.005634ms","start":"2026-05-21T03:30:57.687874Z","end":"2026-05-21T03:30:58.271879Z","steps":["trace[21286101] 'agreement among raft nodes before linearized reading' (duration: 583.949643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:58.27195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:57.687856Z","time spent":"584.086306ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T03:30:58.272056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:57.041015Z","time spent":"1.230775989s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:30:58.272147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.492977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T03:30:58.272181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.823806ms","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-05-21T03:30:58.272219Z","caller":"traceutil/trace.go:171","msg":"trace[421942121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"312.947389ms","start":"2026-05-21T03:30:57.959262Z","end":"2026-05-21T03:30:58.27221Z","steps":["trace[421942121] 'agreement among raft nodes before linearized reading' (duration: 312.804255ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:30:58.272204Z","caller":"traceutil/trace.go:171","msg":"trace[591795937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"135.568609ms","start":"2026-05-21T03:30:58.136627Z","end":"2026-05-21T03:30:58.272196Z","steps":["trace[591795937] 'agreement among raft nodes before linearized reading' (duration: 135.430156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:58.272315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:56.15892Z","time spent":"2.11251934s","remote":"127.0.0.1:39122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036189,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:30:58.27225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:57.959207Z","time spent":"313.03427ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T03:30:59.00446Z","caller":"traceutil/trace.go:171","msg":"trace[1356542579] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"672.111624ms","start":"2026-05-21T03:30:58.332325Z","end":"2026-05-21T03:30:59.004437Z","steps":["trace[1356542579] 'process raft request' (duration: 672.074723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:59.004577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:58.332302Z","time spent":"672.223477ms","remote":"127.0.0.1:39364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:30:59.004692Z","caller":"traceutil/trace.go:171","msg":"trace[1186625714] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"700.870046ms","start":"2026-05-21T03:30:58.303782Z","end":"2026-05-21T03:30:59.004652Z","steps":["trace[1186625714] 'process raft request' (duration: 700.493397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:30:59.004981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:58.303755Z","time spent":"701.093522ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:30:59.481048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.066398ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:30:59.481183Z","caller":"traceutil/trace.go:171","msg":"trace[683950403] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:639; }","duration":"192.211211ms","start":"2026-05-21T03:30:59.288952Z","end":"2026-05-21T03:30:59.481163Z","steps":["trace[683950403] 'range keys from in-memory index tree' (duration: 192.050348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:00.186217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373077639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:31:00.68664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373077639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:31:00.774332Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.195896128s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T03:31:00.775744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234432898s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T03:31:00.776292Z","caller":"traceutil/trace.go:171","msg":"trace[1470412586] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.197918698s","start":"2026-05-21T03:30:59.578346Z","end":"2026-05-21T03:31:00.776265Z","steps":["trace[1470412586] 'process raft request' (duration: 1.197497177s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:00.776437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:59.578322Z","time spent":"1.19803505s","remote":"127.0.0.1:39366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:31:00.776278Z","caller":"traceutil/trace.go:171","msg":"trace[419961525] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"1.09109641s","start":"2026-05-21T03:30:59.685147Z","end":"2026-05-21T03:31:00.776243Z","steps":["trace[419961525] 'read index received' (duration: 1.089324207s)","trace[419961525] 'applied index is now lower than readState.Index' (duration: 1.769793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:01.904952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.219776986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:31:01.905053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115768611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:01.905079Z","caller":"traceutil/trace.go:171","msg":"trace[100253147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"2.219942301s","start":"2026-05-21T03:30:59.685094Z","end":"2026-05-21T03:31:01.905037Z","steps":["trace[100253147] 'agreement among raft nodes before linearized reading' (duration: 1.091254084s)","trace[100253147] 'range keys from in-memory index tree' (duration: 1.128524643s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:01.905054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.634687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:01.905118Z","caller":"traceutil/trace.go:171","msg":"trace[565063563] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:641; }","duration":"1.115871604s","start":"2026-05-21T03:31:00.789229Z","end":"2026-05-21T03:31:01.9051Z","steps":["trace[565063563] 'range keys from in-memory index tree' (duration: 1.11569969s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:01.905113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549572479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T03:31:01.905135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:30:59.685067Z","time spent":"2.220057624s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T03:31:01.905153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:00.789212Z","time spent":"1.115934126s","remote":"127.0.0.1:39378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-05-21T03:31:01.90515Z","caller":"traceutil/trace.go:171","msg":"trace[1222821910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"218.857892ms","start":"2026-05-21T03:31:01.686268Z","end":"2026-05-21T03:31:01.905126Z","steps":["trace[1222821910] 'range keys from in-memory index tree' (duration: 218.479552ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:01.905162Z","caller":"traceutil/trace.go:171","msg":"trace[1246105484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.549641561s","start":"2026-05-21T03:31:00.355504Z","end":"2026-05-21T03:31:01.905146Z","steps":["trace[1246105484] 'agreement among raft nodes before linearized reading' (duration: 421.19396ms)","trace[1246105484] 'range keys from in-memory index tree' (duration: 1.128306628s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:01.905199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:00.355492Z","time spent":"1.549699612s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T03:31:01.905338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549753343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T03:31:01.905343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54921385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-21T03:31:01.905389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600110843s","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-05-21T03:31:01.9054Z","caller":"traceutil/trace.go:171","msg":"trace[697311938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"1.549807025s","start":"2026-05-21T03:31:00.355568Z","end":"2026-05-21T03:31:01.905375Z","steps":["trace[697311938] 'agreement among raft nodes before linearized reading' (duration: 421.117948ms)","trace[697311938] 'range keys from in-memory index tree' (duration: 1.128555304s)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:01.90541Z","caller":"traceutil/trace.go:171","msg":"trace[263351614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"1.549289782s","start":"2026-05-21T03:31:00.356096Z","end":"2026-05-21T03:31:01.905386Z","steps":["trace[263351614] 'agreement among raft nodes before linearized reading' (duration: 420.587115ms)","trace[263351614] 'range keys from in-memory index tree' (duration: 1.128505332s)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:01.905437Z","caller":"traceutil/trace.go:171","msg":"trace[1146204902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.600191055s","start":"2026-05-21T03:31:00.30523Z","end":"2026-05-21T03:31:01.905421Z","steps":["trace[1146204902] 'agreement among raft nodes before linearized reading' (duration: 471.399895ms)","trace[1146204902] 'range keys from in-memory index tree' (duration: 1.128676216s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:01.905449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:00.355523Z","time spent":"1.549912477s","remote":"127.0.0.1:39298","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-05-21T03:31:01.905459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:00.35609Z","time spent":"1.549357155s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T03:31:01.905475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:00.305209Z","time spent":"1.600257666s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T03:31:03.92408Z","caller":"traceutil/trace.go:171","msg":"trace[5294391] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"470.444502ms","start":"2026-05-21T03:31:03.453614Z","end":"2026-05-21T03:31:03.924059Z","steps":["trace[5294391] 'read index received' (duration: 466.597208ms)","trace[5294391] 'applied index is now lower than readState.Index' (duration: 3.846314ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:03.924393Z","caller":"traceutil/trace.go:171","msg":"trace[2075458073] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"471.459987ms","start":"2026-05-21T03:31:03.452912Z","end":"2026-05-21T03:31:03.924372Z","steps":["trace[2075458073] 'process raft request' (duration: 467.397957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:03.924417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.77208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:03.924509Z","caller":"traceutil/trace.go:171","msg":"trace[1059239931] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:684; }","duration":"470.876333ms","start":"2026-05-21T03:31:03.45359Z","end":"2026-05-21T03:31:03.924466Z","steps":["trace[1059239931] 'agreement among raft nodes before linearized reading' (duration: 470.721559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:03.924424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.150264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:31:03.924625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:03.453578Z","time spent":"471.001836ms","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-21T03:31:03.924703Z","caller":"traceutil/trace.go:171","msg":"trace[908045431] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:684; }","duration":"270.525293ms","start":"2026-05-21T03:31:03.654116Z","end":"2026-05-21T03:31:03.924642Z","steps":["trace[908045431] 'agreement among raft nodes before linearized reading' (duration: 270.182095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:03.924788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:03.452896Z","time spent":"471.56108ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:31:03.924822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.265832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:03.925093Z","caller":"traceutil/trace.go:171","msg":"trace[681521812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"237.452935ms","start":"2026-05-21T03:31:03.687447Z","end":"2026-05-21T03:31:03.9249Z","steps":["trace[681521812] 'agreement among raft nodes before linearized reading' (duration: 236.987714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:16.349615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.441668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:31:16.349745Z","caller":"traceutil/trace.go:171","msg":"trace[556858258] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"306.574026ms","start":"2026-05-21T03:31:16.04315Z","end":"2026-05-21T03:31:16.349724Z","steps":["trace[556858258] 'process raft request' (duration: 166.920963ms)","trace[556858258] 'compare' (duration: 139.273884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:16.349873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:16.043133Z","time spent":"306.653569ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:31:16.56522Z","caller":"traceutil/trace.go:171","msg":"trace[273294500] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"521.809593ms","start":"2026-05-21T03:31:16.043364Z","end":"2026-05-21T03:31:16.565173Z","steps":["trace[273294500] 'process raft request' (duration: 478.895247ms)","trace[273294500] 'compare' (duration: 42.571427ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:16.565357Z","caller":"traceutil/trace.go:171","msg":"trace[1340550818] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"520.88997ms","start":"2026-05-21T03:31:16.044429Z","end":"2026-05-21T03:31:16.565319Z","steps":["trace[1340550818] 'process raft request' (duration: 520.664224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:16.565505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:16.043347Z","time spent":"522.010978ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:31:16.565585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:16.044419Z","time spent":"521.032783ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:31:19.800526Z","caller":"traceutil/trace.go:171","msg":"trace[1150987172] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"477.566553ms","start":"2026-05-21T03:31:19.322926Z","end":"2026-05-21T03:31:19.800492Z","steps":["trace[1150987172] 'read index received' (duration: 477.305447ms)","trace[1150987172] 'applied index is now lower than readState.Index' (duration: 260.206µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:19.800676Z","caller":"traceutil/trace.go:171","msg":"trace[1083782273] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"866.626888ms","start":"2026-05-21T03:31:18.934037Z","end":"2026-05-21T03:31:19.800664Z","steps":["trace[1083782273] 'process raft request' (duration: 866.265129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:19.800924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.977664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:31:19.800985Z","caller":"traceutil/trace.go:171","msg":"trace[120574077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:820; }","duration":"478.086356ms","start":"2026-05-21T03:31:19.322882Z","end":"2026-05-21T03:31:19.800969Z","steps":["trace[120574077] 'agreement among raft nodes before linearized reading' (duration: 477.857771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:19.801065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:19.322865Z","time spent":"478.18923ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:31:19.80102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:18.934008Z","time spent":"866.69711ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:31:20.059139Z","caller":"traceutil/trace.go:171","msg":"trace[442520025] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"649.286309ms","start":"2026-05-21T03:31:19.409809Z","end":"2026-05-21T03:31:20.059096Z","steps":["trace[442520025] 'process raft request' (duration: 648.970891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:20.059474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:19.409785Z","time spent":"649.466363ms","remote":"127.0.0.1:39264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:31:20.06096Z","caller":"traceutil/trace.go:171","msg":"trace[267180093] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"255.580417ms","start":"2026-05-21T03:31:19.805353Z","end":"2026-05-21T03:31:20.060933Z","steps":["trace[267180093] 'process raft request' (duration: 255.478715ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:20.061118Z","caller":"traceutil/trace.go:171","msg":"trace[1272863490] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:847; }","duration":"260.46544ms","start":"2026-05-21T03:31:19.80063Z","end":"2026-05-21T03:31:20.061096Z","steps":["trace[1272863490] 'read index received' (duration: 258.134651ms)","trace[1272863490] 'applied index is now lower than readState.Index' (duration: 2.328409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:20.062254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.948082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:20.062348Z","caller":"traceutil/trace.go:171","msg":"trace[975964870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:822; }","duration":"376.113996ms","start":"2026-05-21T03:31:19.686212Z","end":"2026-05-21T03:31:20.062326Z","steps":["trace[975964870] 'agreement among raft nodes before linearized reading' (duration: 375.08342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:20.062401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:19.686194Z","time spent":"376.199199ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:31:32.942838Z","caller":"traceutil/trace.go:171","msg":"trace[757621889] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"223.042863ms","start":"2026-05-21T03:31:32.71976Z","end":"2026-05-21T03:31:32.942803Z","steps":["trace[757621889] 'process raft request' (duration: 222.714005ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:46.864618Z","caller":"traceutil/trace.go:171","msg":"trace[239307343] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"100.290158ms","start":"2026-05-21T03:31:46.76429Z","end":"2026-05-21T03:31:46.86458Z","steps":["trace[239307343] 'process raft request' (duration: 100.073202ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:51.017995Z","caller":"traceutil/trace.go:171","msg":"trace[1344350085] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"120.15047ms","start":"2026-05-21T03:31:50.897805Z","end":"2026-05-21T03:31:51.017956Z","steps":["trace[1344350085] 'process raft request' (duration: 83.602955ms)","trace[1344350085] 'compare' (duration: 36.3578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:54.829583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373078451,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:31:54.967745Z","caller":"traceutil/trace.go:171","msg":"trace[1236233086] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"638.74113ms","start":"2026-05-21T03:31:54.328969Z","end":"2026-05-21T03:31:54.96771Z","steps":["trace[1236233086] 'read index received' (duration: 638.698779ms)","trace[1236233086] 'applied index is now lower than readState.Index' (duration: 40.571µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:54.967753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.321864Z","time spent":"645.881431ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T03:31:54.968014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.023088ms","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-05-21T03:31:54.968089Z","caller":"traceutil/trace.go:171","msg":"trace[642856510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1030; }","duration":"639.11273ms","start":"2026-05-21T03:31:54.328951Z","end":"2026-05-21T03:31:54.968064Z","steps":["trace[642856510] 'agreement among raft nodes before linearized reading' (duration: 638.879934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:54.968325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.328913Z","time spent":"639.404888ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T03:31:55.924519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.941976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:31:55.92489Z","caller":"traceutil/trace.go:171","msg":"trace[1580730721] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"776.384714ms","start":"2026-05-21T03:31:55.148481Z","end":"2026-05-21T03:31:55.924866Z","steps":["trace[1580730721] 'process raft request' (duration: 776.342063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.924964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:55.148447Z","time spent":"776.486687ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:31:55.925092Z","caller":"traceutil/trace.go:171","msg":"trace[491500004] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"1.019822744s","start":"2026-05-21T03:31:54.905196Z","end":"2026-05-21T03:31:55.925019Z","steps":["trace[491500004] 'process raft request' (duration: 248.222781ms)","trace[491500004] 'compare' (duration: 770.522186ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:55.925103Z","caller":"traceutil/trace.go:171","msg":"trace[1550544293] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"955.500906ms","start":"2026-05-21T03:31:54.96952Z","end":"2026-05-21T03:31:55.925021Z","steps":["trace[1550544293] 'process raft request' (duration: 955.139047ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:55.925198Z","caller":"traceutil/trace.go:171","msg":"trace[131886262] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"952.020659ms","start":"2026-05-21T03:31:54.973168Z","end":"2026-05-21T03:31:55.925188Z","steps":["trace[131886262] 'process raft request' (duration: 951.608108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.925249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.973155Z","time spent":"952.068299ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:31:55.925286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.905161Z","time spent":"1.020021958s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:31:55.925311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.969483Z","time spent":"955.715192ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:31:55.925344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529428607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:31:55.925407Z","caller":"traceutil/trace.go:171","msg":"trace[1460751025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"1.529551081s","start":"2026-05-21T03:31:54.395842Z","end":"2026-05-21T03:31:55.925393Z","steps":["trace[1460751025] 'agreement among raft nodes before linearized reading' (duration: 1.529336865s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.925417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194471023s","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-05-21T03:31:55.925114Z","caller":"traceutil/trace.go:171","msg":"trace[299834335] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"957.183239ms","start":"2026-05-21T03:31:54.967835Z","end":"2026-05-21T03:31:55.925018Z","steps":["trace[299834335] 'read index received' (duration: 185.657608ms)","trace[299834335] 'applied index is now lower than readState.Index' (duration: 771.522171ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:55.92546Z","caller":"traceutil/trace.go:171","msg":"trace[856396923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"1.194558405s","start":"2026-05-21T03:31:54.73089Z","end":"2026-05-21T03:31:55.925448Z","steps":["trace[856396923] 'agreement among raft nodes before linearized reading' (duration: 1.194428012s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.9255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.730865Z","time spent":"1.194627437s","remote":"127.0.0.1:39298","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-05-21T03:31:55.925509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203769738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:55.925543Z","caller":"traceutil/trace.go:171","msg":"trace[1127309802] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1034; }","duration":"1.20385668s","start":"2026-05-21T03:31:54.721674Z","end":"2026-05-21T03:31:55.925531Z","steps":["trace[1127309802] 'agreement among raft nodes before linearized reading' (duration: 1.203802149s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.925581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.72164Z","time spent":"1.203933542s","remote":"127.0.0.1:39346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:31:55.925621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.574165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-21T03:31:55.925655Z","caller":"traceutil/trace.go:171","msg":"trace[1351920379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"896.656718ms","start":"2026-05-21T03:31:55.02899Z","end":"2026-05-21T03:31:55.925647Z","steps":["trace[1351920379] 'agreement among raft nodes before linearized reading' (duration: 896.521225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.925668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22756614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T03:31:55.925684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:55.028954Z","time spent":"896.72601ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T03:31:55.925708Z","caller":"traceutil/trace.go:171","msg":"trace[415925154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1034; }","duration":"1.227626241s","start":"2026-05-21T03:31:54.698071Z","end":"2026-05-21T03:31:55.925698Z","steps":["trace[415925154] 'agreement among raft nodes before linearized reading' (duration: 1.227538089s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.925455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.395818Z","time spent":"1.529627722s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:31:55.925738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193444047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:55.925772Z","caller":"traceutil/trace.go:171","msg":"trace[1597569828] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1034; }","duration":"1.193481798s","start":"2026-05-21T03:31:54.732281Z","end":"2026-05-21T03:31:55.925763Z","steps":["trace[1597569828] 'agreement among raft nodes before linearized reading' (duration: 1.193410776s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.925811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.732247Z","time spent":"1.19355372s","remote":"127.0.0.1:34706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:31:55.92583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.498063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T03:31:55.925835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23899314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:55.925861Z","caller":"traceutil/trace.go:171","msg":"trace[1460837548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"843.548214ms","start":"2026-05-21T03:31:55.082305Z","end":"2026-05-21T03:31:55.925853Z","steps":["trace[1460837548] 'agreement among raft nodes before linearized reading' (duration: 843.459112ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:55.925871Z","caller":"traceutil/trace.go:171","msg":"trace[1231578105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"1.239070411s","start":"2026-05-21T03:31:54.686789Z","end":"2026-05-21T03:31:55.925859Z","steps":["trace[1231578105] 'agreement among raft nodes before linearized reading' (duration: 1.23902025s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.925899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:55.082287Z","time spent":"843.604015ms","remote":"127.0.0.1:39298","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-05-21T03:31:55.925902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.686762Z","time spent":"1.239134162s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T03:31:55.925676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164521326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:31:55.925979Z","caller":"traceutil/trace.go:171","msg":"trace[46404539] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1034; }","duration":"1.164866244s","start":"2026-05-21T03:31:54.761104Z","end":"2026-05-21T03:31:55.92597Z","steps":["trace[46404539] 'agreement among raft nodes before linearized reading' (duration: 1.164539306s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:55.926014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.76109Z","time spent":"1.164913705s","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:31:55.925742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:31:54.698062Z","time spent":"1.227669113s","remote":"127.0.0.1:39060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:31:56.170887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.984815ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:31:56.171031Z","caller":"traceutil/trace.go:171","msg":"trace[435633036] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"239.782407ms","start":"2026-05-21T03:31:55.931227Z","end":"2026-05-21T03:31:56.17101Z","steps":["trace[435633036] 'process raft request' (duration: 138.589507ms)","trace[435633036] 'compare' (duration: 100.843281ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:31:56.172209Z","caller":"traceutil/trace.go:171","msg":"trace[313770075] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1071; }","duration":"239.872149ms","start":"2026-05-21T03:31:55.932323Z","end":"2026-05-21T03:31:56.172195Z","steps":["trace[313770075] 'read index received' (duration: 137.575081ms)","trace[313770075] 'applied index is now lower than readState.Index' (duration: 102.295858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:31:56.172481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.981171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:31:56.172547Z","caller":"traceutil/trace.go:171","msg":"trace[2098949580] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"240.326771ms","start":"2026-05-21T03:31:55.932198Z","end":"2026-05-21T03:31:56.172524Z","steps":["trace[2098949580] 'process raft request' (duration: 239.961441ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:56.172562Z","caller":"traceutil/trace.go:171","msg":"trace[997616572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"238.061614ms","start":"2026-05-21T03:31:55.934469Z","end":"2026-05-21T03:31:56.172531Z","steps":["trace[997616572] 'agreement among raft nodes before linearized reading' (duration: 237.981882ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:56.172553Z","caller":"traceutil/trace.go:171","msg":"trace[1283788322] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"241.08894ms","start":"2026-05-21T03:31:55.931414Z","end":"2026-05-21T03:31:56.172503Z","steps":["trace[1283788322] 'process raft request' (duration: 240.71084ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:31:56.172613Z","caller":"traceutil/trace.go:171","msg":"trace[155424928] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"241.216113ms","start":"2026-05-21T03:31:55.931371Z","end":"2026-05-21T03:31:56.172587Z","steps":["trace[155424928] 'process raft request' (duration: 240.68118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:31:56.173183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.840204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T03:31:56.173239Z","caller":"traceutil/trace.go:171","msg":"trace[1603585537] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1038; }","duration":"240.916526ms","start":"2026-05-21T03:31:55.932307Z","end":"2026-05-21T03:31:56.173223Z","steps":["trace[1603585537] 'agreement among raft nodes before linearized reading' (duration: 240.151867ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:20.621299Z","caller":"traceutil/trace.go:171","msg":"trace[1672619539] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"191.369203ms","start":"2026-05-21T03:32:20.429902Z","end":"2026-05-21T03:32:20.621272Z","steps":["trace[1672619539] 'process raft request' (duration: 191.195829ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:20.621421Z","caller":"traceutil/trace.go:171","msg":"trace[1574230069] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1155; }","duration":"191.007564ms","start":"2026-05-21T03:32:20.430385Z","end":"2026-05-21T03:32:20.621393Z","steps":["trace[1574230069] 'read index received' (duration: 190.998264ms)","trace[1574230069] 'applied index is now lower than readState.Index' (duration: 7.07µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:20.621864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.461196ms","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-05-21T03:32:20.621912Z","caller":"traceutil/trace.go:171","msg":"trace[526171859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"191.558237ms","start":"2026-05-21T03:32:20.430342Z","end":"2026-05-21T03:32:20.621901Z","steps":["trace[526171859] 'agreement among raft nodes before linearized reading' (duration: 191.350652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:20.62228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.854134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T03:32:20.622289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.144628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:32:20.622342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.127616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:32:20.62236Z","caller":"traceutil/trace.go:171","msg":"trace[59830485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"191.970118ms","start":"2026-05-21T03:32:20.430371Z","end":"2026-05-21T03:32:20.622341Z","steps":["trace[59830485] 'agreement among raft nodes before linearized reading' (duration: 191.743832ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:20.622401Z","caller":"traceutil/trace.go:171","msg":"trace[551773360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"188.200268ms","start":"2026-05-21T03:32:20.434187Z","end":"2026-05-21T03:32:20.622388Z","steps":["trace[551773360] 'agreement among raft nodes before linearized reading' (duration: 188.002283ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:20.622381Z","caller":"traceutil/trace.go:171","msg":"trace[1507004126] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1113; }","duration":"143.323562ms","start":"2026-05-21T03:32:20.479032Z","end":"2026-05-21T03:32:20.622355Z","steps":["trace[1507004126] 'agreement among raft nodes before linearized reading' (duration: 143.091866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:21.190046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373078670,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:32:21.454431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.778192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:32:21.454533Z","caller":"traceutil/trace.go:171","msg":"trace[833244804] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"827.74547ms","start":"2026-05-21T03:32:20.626769Z","end":"2026-05-21T03:32:21.454514Z","steps":["trace[833244804] 'process raft request' (duration: 662.763243ms)","trace[833244804] 'compare' (duration: 164.67458ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:21.454669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:20.626715Z","time spent":"827.848592ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:32:22.110088Z","caller":"traceutil/trace.go:171","msg":"trace[1157748707] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"1.48321419s","start":"2026-05-21T03:32:20.626848Z","end":"2026-05-21T03:32:22.110062Z","steps":["trace[1157748707] 'process raft request' (duration: 1.468378208s)","trace[1157748707] 'compare' (duration: 14.624347ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:22.110222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:20.626826Z","time spent":"1.483323703s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:32:22.110493Z","caller":"traceutil/trace.go:171","msg":"trace[289170189] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1155; }","duration":"1.42126195s","start":"2026-05-21T03:32:20.689216Z","end":"2026-05-21T03:32:22.110478Z","steps":["trace[289170189] 'read index received' (duration: 600.363453ms)","trace[289170189] 'applied index is now lower than readState.Index' (duration: 820.897707ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:22.110616Z","caller":"traceutil/trace.go:171","msg":"trace[585056606] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"1.483686401s","start":"2026-05-21T03:32:20.626919Z","end":"2026-05-21T03:32:22.110605Z","steps":["trace[585056606] 'process raft request' (duration: 1.483078677s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:22.110707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:20.626912Z","time spent":"1.483751993s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:32:22.110866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421627149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:32:22.110908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06615221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:32:22.110931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.772674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:32:22.110965Z","caller":"traceutil/trace.go:171","msg":"trace[682497472] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"918.680869ms","start":"2026-05-21T03:32:21.192166Z","end":"2026-05-21T03:32:22.110847Z","steps":["trace[682497472] 'process raft request' (duration: 918.258009ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:22.110951Z","caller":"traceutil/trace.go:171","msg":"trace[1553388622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"1.421754862s","start":"2026-05-21T03:32:20.689177Z","end":"2026-05-21T03:32:22.110932Z","steps":["trace[1553388622] 'agreement among raft nodes before linearized reading' (duration: 1.421581308s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:22.111034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:20.689159Z","time spent":"1.421863484s","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:32:22.110971Z","caller":"traceutil/trace.go:171","msg":"trace[1609080432] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1117; }","duration":"1.066270692s","start":"2026-05-21T03:32:21.044687Z","end":"2026-05-21T03:32:22.110957Z","steps":["trace[1609080432] 'agreement among raft nodes before linearized reading' (duration: 1.06616518s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:22.111292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:21.044665Z","time spent":"1.066609321s","remote":"127.0.0.1:39396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:32:22.111017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.844481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:32:22.111454Z","caller":"traceutil/trace.go:171","msg":"trace[1436631708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1117; }","duration":"924.349824ms","start":"2026-05-21T03:32:21.187088Z","end":"2026-05-21T03:32:22.111437Z","steps":["trace[1436631708] 'agreement among raft nodes before linearized reading' (duration: 923.854022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:22.111535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:21.187067Z","time spent":"924.419865ms","remote":"127.0.0.1:34600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-21T03:32:22.110978Z","caller":"traceutil/trace.go:171","msg":"trace[1311174257] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1117; }","duration":"170.824376ms","start":"2026-05-21T03:32:21.94014Z","end":"2026-05-21T03:32:22.110965Z","steps":["trace[1311174257] 'agreement among raft nodes before linearized reading' (duration: 170.744854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:22.111189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:21.19212Z","time spent":"918.912984ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:32:22.425746Z","caller":"traceutil/trace.go:171","msg":"trace[665508863] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"196.892915ms","start":"2026-05-21T03:32:22.22883Z","end":"2026-05-21T03:32:22.425723Z","steps":["trace[665508863] 'read index received' (duration: 178.99867ms)","trace[665508863] 'applied index is now lower than readState.Index' (duration: 17.893185ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:22.425873Z","caller":"traceutil/trace.go:171","msg":"trace[1953907477] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"206.646515ms","start":"2026-05-21T03:32:22.219185Z","end":"2026-05-21T03:32:22.425831Z","steps":["trace[1953907477] 'process raft request' (duration: 188.73133ms)","trace[1953907477] 'compare' (duration: 17.463954ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:22.425915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.077149ms","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-05-21T03:32:22.425942Z","caller":"traceutil/trace.go:171","msg":"trace[1431908818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"197.150271ms","start":"2026-05-21T03:32:22.228783Z","end":"2026-05-21T03:32:22.425933Z","steps":["trace[1431908818] 'agreement among raft nodes before linearized reading' (duration: 197.020908ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:23.57774Z","caller":"traceutil/trace.go:171","msg":"trace[752062877] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"114.776124ms","start":"2026-05-21T03:32:23.462941Z","end":"2026-05-21T03:32:23.577717Z","steps":["trace[752062877] 'process raft request' (duration: 114.628371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:24.566423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.057012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:32:24.566918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.838949ms","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-05-21T03:32:24.567062Z","caller":"traceutil/trace.go:171","msg":"trace[1026797157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1121; }","duration":"450.022474ms","start":"2026-05-21T03:32:24.117016Z","end":"2026-05-21T03:32:24.567038Z","steps":["trace[1026797157] 'range keys from in-memory index tree' (duration: 449.675396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:24.567176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:24.117001Z","time spent":"450.160827ms","remote":"127.0.0.1:39298","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-05-21T03:32:24.567098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.920441ms","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-05-21T03:32:24.56731Z","caller":"traceutil/trace.go:171","msg":"trace[1900448220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"450.188418ms","start":"2026-05-21T03:32:24.117107Z","end":"2026-05-21T03:32:24.567295Z","steps":["trace[1900448220] 'range keys from in-memory index tree' (duration: 449.713716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:24.567175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.042421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-21T03:32:24.56736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:24.117079Z","time spent":"450.270099ms","remote":"127.0.0.1:39298","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":"info","ts":"2026-05-21T03:32:24.567391Z","caller":"traceutil/trace.go:171","msg":"trace[269582066] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1121; }","duration":"246.330798ms","start":"2026-05-21T03:32:24.321035Z","end":"2026-05-21T03:32:24.567365Z","steps":["trace[269582066] 'range keys from in-memory index tree' (duration: 245.875667ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:24.567533Z","caller":"traceutil/trace.go:171","msg":"trace[1697264929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"880.215586ms","start":"2026-05-21T03:32:23.686284Z","end":"2026-05-21T03:32:24.566499Z","steps":["trace[1697264929] 'range keys from in-memory index tree' (duration: 879.973231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:24.567572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:23.686207Z","time spent":"881.356883ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:32:24.568897Z","caller":"traceutil/trace.go:171","msg":"trace[1928786208] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"113.004393ms","start":"2026-05-21T03:32:24.455876Z","end":"2026-05-21T03:32:24.56888Z","steps":["trace[1928786208] 'read index received' (duration: 64.807859ms)","trace[1928786208] 'applied index is now lower than readState.Index' (duration: 48.195524ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:24.569008Z","caller":"traceutil/trace.go:171","msg":"trace[294102871] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"132.986457ms","start":"2026-05-21T03:32:24.436008Z","end":"2026-05-21T03:32:24.568994Z","steps":["trace[294102871] 'process raft request' (duration: 84.691701ms)","trace[294102871] 'compare' (duration: 45.351766ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:24.569198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.309241ms","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-05-21T03:32:24.56926Z","caller":"traceutil/trace.go:171","msg":"trace[1217410938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1122; }","duration":"113.393142ms","start":"2026-05-21T03:32:24.455852Z","end":"2026-05-21T03:32:24.569245Z","steps":["trace[1217410938] 'agreement among raft nodes before linearized reading' (duration: 113.202608ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:24.791483Z","caller":"traceutil/trace.go:171","msg":"trace[1896306678] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"218.774623ms","start":"2026-05-21T03:32:24.572688Z","end":"2026-05-21T03:32:24.791463Z","steps":["trace[1896306678] 'process raft request' (duration: 218.703322ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:24.791485Z","caller":"traceutil/trace.go:171","msg":"trace[236116406] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"219.087081ms","start":"2026-05-21T03:32:24.57237Z","end":"2026-05-21T03:32:24.791457Z","steps":["trace[236116406] 'process raft request' (duration: 218.897987ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:24.791709Z","caller":"traceutil/trace.go:171","msg":"trace[1109526121] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1164; }","duration":"144.406379ms","start":"2026-05-21T03:32:24.64728Z","end":"2026-05-21T03:32:24.791686Z","steps":["trace[1109526121] 'read index received' (duration: 84.21948ms)","trace[1109526121] 'applied index is now lower than readState.Index' (duration: 60.184299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:24.791899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.610343ms","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-05-21T03:32:24.791976Z","caller":"traceutil/trace.go:171","msg":"trace[1518871828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1125; }","duration":"144.736566ms","start":"2026-05-21T03:32:24.647227Z","end":"2026-05-21T03:32:24.791964Z","steps":["trace[1518871828] 'agreement among raft nodes before linearized reading' (duration: 144.558312ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:24.791877Z","caller":"traceutil/trace.go:171","msg":"trace[595853138] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"218.970609ms","start":"2026-05-21T03:32:24.5728Z","end":"2026-05-21T03:32:24.79177Z","steps":["trace[595853138] 'process raft request' (duration: 218.62897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:24.797854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.541034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:32:24.797927Z","caller":"traceutil/trace.go:171","msg":"trace[1879920098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1126; }","duration":"110.648336ms","start":"2026-05-21T03:32:24.68726Z","end":"2026-05-21T03:32:24.797909Z","steps":["trace[1879920098] 'agreement among raft nodes before linearized reading' (duration: 110.515973ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:26.907914Z","caller":"traceutil/trace.go:171","msg":"trace[1452828170] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"333.011375ms","start":"2026-05-21T03:32:26.574879Z","end":"2026-05-21T03:32:26.90789Z","steps":["trace[1452828170] 'process raft request' (duration: 332.885312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:26.908046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:26.574852Z","time spent":"333.121297ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:32:27.314065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.863819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:32:27.314162Z","caller":"traceutil/trace.go:171","msg":"trace[1115153892] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"518.262412ms","start":"2026-05-21T03:32:26.795883Z","end":"2026-05-21T03:32:27.314146Z","steps":["trace[1115153892] 'read index received' (duration: 112.075549ms)","trace[1115153892] 'applied index is now lower than readState.Index' (duration: 406.185693ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:27.314367Z","caller":"traceutil/trace.go:171","msg":"trace[185220830] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"632.312719ms","start":"2026-05-21T03:32:26.682017Z","end":"2026-05-21T03:32:27.31433Z","steps":["trace[185220830] 'process raft request' (duration: 356.061631ms)","trace[185220830] 'compare' (duration: 275.766957ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:27.314518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.640152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T03:32:27.314553Z","caller":"traceutil/trace.go:171","msg":"trace[728991220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1130; }","duration":"518.723793ms","start":"2026-05-21T03:32:26.795819Z","end":"2026-05-21T03:32:27.314543Z","steps":["trace[728991220] 'agreement among raft nodes before linearized reading' (duration: 518.5743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:27.314554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:26.681993Z","time spent":"632.460203ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:32:27.314589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:26.795798Z","time spent":"518.779614ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T03:32:27.314963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.802765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-21T03:32:27.315057Z","caller":"traceutil/trace.go:171","msg":"trace[1048613778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1130; }","duration":"518.956899ms","start":"2026-05-21T03:32:26.79608Z","end":"2026-05-21T03:32:27.315037Z","steps":["trace[1048613778] 'agreement among raft nodes before linearized reading' (duration: 518.626531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:27.315173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:26.796068Z","time spent":"519.064061ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T03:32:27.315191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.233791ms","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-05-21T03:32:27.315286Z","caller":"traceutil/trace.go:171","msg":"trace[1154902404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1130; }","duration":"518.359514ms","start":"2026-05-21T03:32:26.796904Z","end":"2026-05-21T03:32:27.315264Z","steps":["trace[1154902404] 'agreement among raft nodes before linearized reading' (duration: 518.126489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:27.315335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:26.796885Z","time spent":"518.437897ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T03:32:27.315207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.556231ms","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-05-21T03:32:27.315398Z","caller":"traceutil/trace.go:171","msg":"trace[771924453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1130; }","duration":"497.901139ms","start":"2026-05-21T03:32:26.817475Z","end":"2026-05-21T03:32:27.315376Z","steps":["trace[771924453] 'agreement among raft nodes before linearized reading' (duration: 497.50824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:27.31546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:26.817455Z","time spent":"497.987191ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T03:32:27.57605Z","caller":"traceutil/trace.go:171","msg":"trace[200410116] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"251.963231ms","start":"2026-05-21T03:32:27.324056Z","end":"2026-05-21T03:32:27.57602Z","steps":["trace[200410116] 'process raft request' (duration: 220.601477ms)","trace[200410116] 'compare' (duration: 31.130159ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:27.595046Z","caller":"traceutil/trace.go:171","msg":"trace[417001367] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"269.98747ms","start":"2026-05-21T03:32:27.325041Z","end":"2026-05-21T03:32:27.595029Z","steps":["trace[417001367] 'process raft request' (duration: 269.819556ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:27.595299Z","caller":"traceutil/trace.go:171","msg":"trace[208129507] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"270.176814ms","start":"2026-05-21T03:32:27.325103Z","end":"2026-05-21T03:32:27.59528Z","steps":["trace[208129507] 'process raft request' (duration: 269.883067ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:27.595282Z","caller":"traceutil/trace.go:171","msg":"trace[1492554561] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"270.116002ms","start":"2026-05-21T03:32:27.325132Z","end":"2026-05-21T03:32:27.595248Z","steps":["trace[1492554561] 'process raft request' (duration: 270.02167ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:37.783921Z","caller":"traceutil/trace.go:171","msg":"trace[429978294] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"133.253074ms","start":"2026-05-21T03:32:37.650637Z","end":"2026-05-21T03:32:37.78389Z","steps":["trace[429978294] 'process raft request' (duration: 96.674105ms)","trace[429978294] 'compare' (duration: 36.415555ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:37.784143Z","caller":"traceutil/trace.go:171","msg":"trace[473353915] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"133.441788ms","start":"2026-05-21T03:32:37.650661Z","end":"2026-05-21T03:32:37.784103Z","steps":["trace[473353915] 'process raft request' (duration: 133.189572ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:39.069304Z","caller":"traceutil/trace.go:171","msg":"trace[96688562] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"114.979529ms","start":"2026-05-21T03:32:38.9543Z","end":"2026-05-21T03:32:39.06928Z","steps":["trace[96688562] 'process raft request' (duration: 114.833456ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:41.913847Z","caller":"traceutil/trace.go:171","msg":"trace[460424024] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"115.478992ms","start":"2026-05-21T03:32:41.798337Z","end":"2026-05-21T03:32:41.913816Z","steps":["trace[460424024] 'process raft request' (duration: 115.341708ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:32:41.913794Z","caller":"traceutil/trace.go:171","msg":"trace[1807862708] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1276; }","duration":"114.889918ms","start":"2026-05-21T03:32:41.798885Z","end":"2026-05-21T03:32:41.913775Z","steps":["trace[1807862708] 'read index received' (duration: 96.962832ms)","trace[1807862708] 'applied index is now lower than readState.Index' (duration: 17.926486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:32:41.914032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.150644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:32:41.914056Z","caller":"traceutil/trace.go:171","msg":"trace[1152904750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1230; }","duration":"115.282047ms","start":"2026-05-21T03:32:41.798768Z","end":"2026-05-21T03:32:41.91405Z","steps":["trace[1152904750] 'agreement among raft nodes before linearized reading' (duration: 115.165804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:44.229989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373078937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:32:44.731142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373078937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:32:45.231706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373078937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T03:32:45.627286Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.957782806s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T03:32:45.62805Z","caller":"traceutil/trace.go:171","msg":"trace[886111017] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.958424661s","start":"2026-05-21T03:32:43.669413Z","end":"2026-05-21T03:32:45.627838Z","steps":["trace[886111017] 'process raft request' (duration: 1.958282338s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.628406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:43.669389Z","time spent":"1.958747968s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:32:45.632293Z","caller":"traceutil/trace.go:171","msg":"trace[390083018] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"1.902384241s","start":"2026-05-21T03:32:43.729893Z","end":"2026-05-21T03:32:45.632277Z","steps":["trace[390083018] 'read index received' (duration: 1.897812972s)","trace[390083018] 'applied index is now lower than readState.Index' (duration: 4.570419ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:32:45.632525Z","caller":"traceutil/trace.go:171","msg":"trace[938666365] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"540.238024ms","start":"2026-05-21T03:32:45.092275Z","end":"2026-05-21T03:32:45.632513Z","steps":["trace[938666365] 'process raft request' (duration: 539.930597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.632602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:45.092249Z","time spent":"540.309656ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:32:45.632865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902977724s","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-05-21T03:32:45.632908Z","caller":"traceutil/trace.go:171","msg":"trace[128014084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1235; }","duration":"1.903054226s","start":"2026-05-21T03:32:43.729844Z","end":"2026-05-21T03:32:45.632898Z","steps":["trace[128014084] 'agreement among raft nodes before linearized reading' (duration: 1.902930093s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.632941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:43.729824Z","time spent":"1.903110398s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:32:45.633118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.732533198s","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-05-21T03:32:45.633151Z","caller":"traceutil/trace.go:171","msg":"trace[1856073555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"1.732597709s","start":"2026-05-21T03:32:43.900545Z","end":"2026-05-21T03:32:45.633143Z","steps":["trace[1856073555] 'agreement among raft nodes before linearized reading' (duration: 1.732518677s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.633177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:43.900525Z","time spent":"1.732646561s","remote":"127.0.0.1:39298","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-05-21T03:32:45.633366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.017266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:32:45.633404Z","caller":"traceutil/trace.go:171","msg":"trace[1503180892] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1235; }","duration":"753.056577ms","start":"2026-05-21T03:32:44.880337Z","end":"2026-05-21T03:32:45.633394Z","steps":["trace[1503180892] 'agreement among raft nodes before linearized reading' (duration: 752.997896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.633434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:44.880275Z","time spent":"753.150169ms","remote":"127.0.0.1:39466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:32:45.633632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.406856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:32:45.63368Z","caller":"traceutil/trace.go:171","msg":"trace[1024629764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"946.531579ms","start":"2026-05-21T03:32:44.687136Z","end":"2026-05-21T03:32:45.633668Z","steps":["trace[1024629764] 'agreement among raft nodes before linearized reading' (duration: 946.456267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.633722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:44.687112Z","time spent":"946.601401ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T03:32:45.633968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311572124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T03:32:45.634017Z","caller":"traceutil/trace.go:171","msg":"trace[1657640716] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1235; }","duration":"1.311674528s","start":"2026-05-21T03:32:44.322331Z","end":"2026-05-21T03:32:45.634005Z","steps":["trace[1657640716] 'agreement among raft nodes before linearized reading' (duration: 1.311551605s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.634053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:44.322299Z","time spent":"1.311744819s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-05-21T03:32:45.63424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569319113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:32:45.634278Z","caller":"traceutil/trace.go:171","msg":"trace[75316482] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1235; }","duration":"1.569389975s","start":"2026-05-21T03:32:44.064879Z","end":"2026-05-21T03:32:45.634269Z","steps":["trace[75316482] 'agreement among raft nodes before linearized reading' (duration: 1.569330973s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.634306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:44.064864Z","time spent":"1.569434556s","remote":"127.0.0.1:39164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:32:45.63449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710569737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:32:45.634524Z","caller":"traceutil/trace.go:171","msg":"trace[1843119959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1235; }","duration":"1.710603728s","start":"2026-05-21T03:32:43.923913Z","end":"2026-05-21T03:32:45.634516Z","steps":["trace[1843119959] 'agreement among raft nodes before linearized reading' (duration: 1.710512716s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.634552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:43.923872Z","time spent":"1.710672299s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:32:45.634686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716368915s","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-05-21T03:32:45.634716Z","caller":"traceutil/trace.go:171","msg":"trace[1493276583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"1.716427676s","start":"2026-05-21T03:32:43.918281Z","end":"2026-05-21T03:32:45.634709Z","steps":["trace[1493276583] 'agreement among raft nodes before linearized reading' (duration: 1.716353225s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:45.634741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:43.918268Z","time spent":"1.716467157s","remote":"127.0.0.1:39298","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":"info","ts":"2026-05-21T03:32:58.298505Z","caller":"traceutil/trace.go:171","msg":"trace[969740505] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"425.100972ms","start":"2026-05-21T03:32:57.873384Z","end":"2026-05-21T03:32:58.298485Z","steps":["trace[969740505] 'process raft request' (duration: 425.070471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:58.298736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:57.873375Z","time spent":"425.275536ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:32:58.298761Z","caller":"traceutil/trace.go:171","msg":"trace[1101418546] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"427.432027ms","start":"2026-05-21T03:32:57.871309Z","end":"2026-05-21T03:32:58.298741Z","steps":["trace[1101418546] 'process raft request' (duration: 426.993007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:58.298831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:57.87128Z","time spent":"427.51749ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:32:58.298917Z","caller":"traceutil/trace.go:171","msg":"trace[1060212991] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"426.72278ms","start":"2026-05-21T03:32:57.872184Z","end":"2026-05-21T03:32:58.298907Z","steps":["trace[1060212991] 'process raft request' (duration: 426.222778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:58.29899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:57.872163Z","time spent":"426.800332ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:32:58.299003Z","caller":"traceutil/trace.go:171","msg":"trace[272108892] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"426.28083ms","start":"2026-05-21T03:32:57.872684Z","end":"2026-05-21T03:32:58.298964Z","steps":["trace[272108892] 'process raft request' (duration: 425.750927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:32:58.299161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:32:57.872666Z","time spent":"426.412673ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:02.01759Z","caller":"traceutil/trace.go:171","msg":"trace[612491303] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"226.297082ms","start":"2026-05-21T03:33:01.791268Z","end":"2026-05-21T03:33:02.017565Z","steps":["trace[612491303] 'process raft request' (duration: 226.055226ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:02.018111Z","caller":"traceutil/trace.go:171","msg":"trace[310865140] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"120.109781ms","start":"2026-05-21T03:33:01.897981Z","end":"2026-05-21T03:33:02.01809Z","steps":["trace[310865140] 'read index received' (duration: 119.412145ms)","trace[310865140] 'applied index is now lower than readState.Index' (duration: 696.806µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:33:02.018133Z","caller":"traceutil/trace.go:171","msg":"trace[1747219256] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"171.541932ms","start":"2026-05-21T03:33:01.846574Z","end":"2026-05-21T03:33:02.018116Z","steps":["trace[1747219256] 'process raft request' (duration: 171.422409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:02.018261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.266945ms","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-05-21T03:33:02.018305Z","caller":"traceutil/trace.go:171","msg":"trace[602518031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"120.337536ms","start":"2026-05-21T03:33:01.897954Z","end":"2026-05-21T03:33:02.018292Z","steps":["trace[602518031] 'agreement among raft nodes before linearized reading' (duration: 120.211123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.075241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.139016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-21T03:33:05.075282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.005405ms","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-05-21T03:33:05.075326Z","caller":"traceutil/trace.go:171","msg":"trace[1109736404] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1322; }","duration":"752.247998ms","start":"2026-05-21T03:33:04.323056Z","end":"2026-05-21T03:33:05.075304Z","steps":["trace[1109736404] 'range keys from in-memory index tree' (duration: 752.008432ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:05.075357Z","caller":"traceutil/trace.go:171","msg":"trace[421216429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"758.139418ms","start":"2026-05-21T03:33:04.3172Z","end":"2026-05-21T03:33:05.075339Z","steps":["trace[421216429] 'range keys from in-memory index tree' (duration: 757.819511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.075369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:04.323036Z","time spent":"752.32498ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-05-21T03:33:05.075401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:04.317176Z","time spent":"758.216149ms","remote":"127.0.0.1:39298","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-05-21T03:33:05.075404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.153743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:33:05.075478Z","caller":"traceutil/trace.go:171","msg":"trace[1076154825] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1322; }","duration":"500.243905ms","start":"2026-05-21T03:33:04.575216Z","end":"2026-05-21T03:33:05.07546Z","steps":["trace[1076154825] 'count revisions from in-memory index tree' (duration: 500.098282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.075517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:04.575181Z","time spent":"500.326037ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:33:05.07553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.483443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:33:05.075539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.942707ms","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-05-21T03:33:05.075584Z","caller":"traceutil/trace.go:171","msg":"trace[1459295349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"391.572216ms","start":"2026-05-21T03:33:04.683997Z","end":"2026-05-21T03:33:05.075569Z","steps":["trace[1459295349] 'range keys from in-memory index tree' (duration: 391.412722ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:05.075613Z","caller":"traceutil/trace.go:171","msg":"trace[1649028752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"751.026969ms","start":"2026-05-21T03:33:04.324569Z","end":"2026-05-21T03:33:05.075596Z","steps":["trace[1649028752] 'range keys from in-memory index tree' (duration: 750.793383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.075689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:04.324536Z","time spent":"751.143341ms","remote":"127.0.0.1:39298","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-05-21T03:33:05.075618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:04.683979Z","time spent":"391.631787ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T03:33:05.075122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.626386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:33:05.075881Z","caller":"traceutil/trace.go:171","msg":"trace[369689850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"750.425045ms","start":"2026-05-21T03:33:04.325445Z","end":"2026-05-21T03:33:05.07587Z","steps":["trace[369689850] 'range keys from in-memory index tree' (duration: 749.492073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.075954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:04.325437Z","time spent":"750.505966ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T03:33:05.075969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.031188ms","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-05-21T03:33:05.076008Z","caller":"traceutil/trace.go:171","msg":"trace[935357348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"756.083409ms","start":"2026-05-21T03:33:04.319914Z","end":"2026-05-21T03:33:05.075997Z","steps":["trace[935357348] 'range keys from in-memory index tree' (duration: 755.124396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.076055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:04.319907Z","time spent":"756.141021ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T03:33:05.614295Z","caller":"traceutil/trace.go:171","msg":"trace[1146795324] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"536.727842ms","start":"2026-05-21T03:33:05.077544Z","end":"2026-05-21T03:33:05.614272Z","steps":["trace[1146795324] 'read index received' (duration: 151.144µs)","trace[1146795324] 'applied index is now lower than readState.Index' (duration: 536.575478ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:05.614335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:05.077464Z","time spent":"536.862346ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T03:33:05.614515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.933757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:33:05.614783Z","caller":"traceutil/trace.go:171","msg":"trace[783335718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"537.08938ms","start":"2026-05-21T03:33:05.077512Z","end":"2026-05-21T03:33:05.614602Z","steps":["trace[783335718] 'agreement among raft nodes before linearized reading' (duration: 536.874035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.614875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:05.077441Z","time spent":"537.416328ms","remote":"127.0.0.1:39000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:33:05.747708Z","caller":"traceutil/trace.go:171","msg":"trace[1787318302] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"667.767262ms","start":"2026-05-21T03:33:05.079917Z","end":"2026-05-21T03:33:05.747685Z","steps":["trace[1787318302] 'process raft request' (duration: 667.547977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.747842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:05.079903Z","time spent":"667.871686ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:05.748054Z","caller":"traceutil/trace.go:171","msg":"trace[1513939392] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"664.311351ms","start":"2026-05-21T03:33:05.083713Z","end":"2026-05-21T03:33:05.748024Z","steps":["trace[1513939392] 'process raft request' (duration: 664.263279ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:05.74808Z","caller":"traceutil/trace.go:171","msg":"trace[993801787] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"664.448404ms","start":"2026-05-21T03:33:05.083606Z","end":"2026-05-21T03:33:05.748054Z","steps":["trace[993801787] 'process raft request' (duration: 664.26239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.74819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:05.083585Z","time spent":"664.545296ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:33:05.748202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:05.083689Z","time spent":"664.436684ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:05.748065Z","caller":"traceutil/trace.go:171","msg":"trace[558169045] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"664.470875ms","start":"2026-05-21T03:33:05.083561Z","end":"2026-05-21T03:33:05.748032Z","steps":["trace[558169045] 'process raft request' (duration: 664.051045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:05.748338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:05.083548Z","time spent":"664.70269ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:05.769992Z","caller":"traceutil/trace.go:171","msg":"trace[1184601552] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"154.507037ms","start":"2026-05-21T03:33:05.615464Z","end":"2026-05-21T03:33:05.769971Z","steps":["trace[1184601552] 'process raft request' (duration: 154.385304ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:06.207461Z","caller":"traceutil/trace.go:171","msg":"trace[95421039] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"147.142052ms","start":"2026-05-21T03:33:06.060297Z","end":"2026-05-21T03:33:06.207439Z","steps":["trace[95421039] 'process raft request' (duration: 56.673525ms)","trace[95421039] 'compare' (duration: 90.372505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:07.106202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.347286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:33:07.10628Z","caller":"traceutil/trace.go:171","msg":"trace[389271008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"422.455348ms","start":"2026-05-21T03:33:06.683808Z","end":"2026-05-21T03:33:07.106263Z","steps":["trace[389271008] 'range keys from in-memory index tree' (duration: 422.285895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:07.106317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:06.683792Z","time spent":"422.5168ms","remote":"127.0.0.1:39000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T03:33:07.106714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.211536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:33:07.10678Z","caller":"traceutil/trace.go:171","msg":"trace[1574519294] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1330; }","duration":"225.430681ms","start":"2026-05-21T03:33:06.881337Z","end":"2026-05-21T03:33:07.106767Z","steps":["trace[1574519294] 'count revisions from in-memory index tree' (duration: 225.141745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.378514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440202373079158,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:33:08.421176Z","caller":"traceutil/trace.go:171","msg":"trace[1319115125] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"663.4587ms","start":"2026-05-21T03:33:07.75769Z","end":"2026-05-21T03:33:08.421149Z","steps":["trace[1319115125] 'process raft request' (duration: 663.303286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.421331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:07.757666Z","time spent":"663.580453ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:08.642183Z","caller":"traceutil/trace.go:171","msg":"trace[1140951193] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"884.418765ms","start":"2026-05-21T03:33:07.757725Z","end":"2026-05-21T03:33:08.642144Z","steps":["trace[1140951193] 'process raft request' (duration: 844.974509ms)","trace[1140951193] 'compare' (duration: 39.011996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:08.642403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:07.757709Z","time spent":"884.59255ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:33:08.642432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.395296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-21T03:33:08.64248Z","caller":"traceutil/trace.go:171","msg":"trace[994413565] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1336; }","duration":"764.491349ms","start":"2026-05-21T03:33:07.877976Z","end":"2026-05-21T03:33:08.642468Z","steps":["trace[994413565] 'agreement among raft nodes before linearized reading' (duration: 764.300654ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:08.642453Z","caller":"traceutil/trace.go:171","msg":"trace[834616876] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"884.393885ms","start":"2026-05-21T03:33:07.758025Z","end":"2026-05-21T03:33:08.642419Z","steps":["trace[834616876] 'process raft request' (duration: 884.044507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.642515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:07.877961Z","time spent":"764.54574ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-21T03:33:08.642594Z","caller":"traceutil/trace.go:171","msg":"trace[1253889600] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"579.678691ms","start":"2026-05-21T03:33:08.062893Z","end":"2026-05-21T03:33:08.642572Z","steps":["trace[1253889600] 'process raft request' (duration: 579.21589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.642614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:07.758016Z","time spent":"884.505587ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:08.642646Z","caller":"traceutil/trace.go:171","msg":"trace[2067813497] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"518.657053ms","start":"2026-05-21T03:33:08.123979Z","end":"2026-05-21T03:33:08.642636Z","steps":["trace[2067813497] 'process raft request' (duration: 518.174022ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:08.642225Z","caller":"traceutil/trace.go:171","msg":"trace[474000373] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1383; }","duration":"764.177862ms","start":"2026-05-21T03:33:07.878018Z","end":"2026-05-21T03:33:08.642196Z","steps":["trace[474000373] 'read index received' (duration: 542.96078ms)","trace[474000373] 'applied index is now lower than readState.Index' (duration: 221.215802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:08.642695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:08.062868Z","time spent":"579.774683ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:33:08.642735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:08.123953Z","time spent":"518.717764ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:08.642834Z","caller":"traceutil/trace.go:171","msg":"trace[2037568136] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"885.02848ms","start":"2026-05-21T03:33:07.757796Z","end":"2026-05-21T03:33:08.642824Z","steps":["trace[2037568136] 'process raft request' (duration: 884.17158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.642865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.281977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:33:08.642891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:07.757767Z","time spent":"885.087841ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:08.642918Z","caller":"traceutil/trace.go:171","msg":"trace[276760577] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1336; }","duration":"535.379169ms","start":"2026-05-21T03:33:08.107519Z","end":"2026-05-21T03:33:08.642899Z","steps":["trace[276760577] 'agreement among raft nodes before linearized reading' (duration: 535.289307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.642991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:08.107495Z","time spent":"535.477402ms","remote":"127.0.0.1:34602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:33:08.643226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.405671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:33:08.643298Z","caller":"traceutil/trace.go:171","msg":"trace[717019645] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1336; }","duration":"375.526314ms","start":"2026-05-21T03:33:08.267758Z","end":"2026-05-21T03:33:08.643285Z","steps":["trace[717019645] 'agreement among raft nodes before linearized reading' (duration: 375.395921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.643333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:08.267736Z","time spent":"375.588676ms","remote":"127.0.0.1:39212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-21T03:33:08.643374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.297552ms","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-05-21T03:33:08.643411Z","caller":"traceutil/trace.go:171","msg":"trace[1622066186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"432.361314ms","start":"2026-05-21T03:33:08.211039Z","end":"2026-05-21T03:33:08.6434Z","steps":["trace[1622066186] 'agreement among raft nodes before linearized reading' (duration: 432.18686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.643441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:08.211023Z","time spent":"432.410545ms","remote":"127.0.0.1:39298","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-05-21T03:33:08.643501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.808475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:33:08.643559Z","caller":"traceutil/trace.go:171","msg":"trace[290847639] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1336; }","duration":"261.905747ms","start":"2026-05-21T03:33:08.381635Z","end":"2026-05-21T03:33:08.643541Z","steps":["trace[290847639] 'agreement among raft nodes before linearized reading' (duration: 261.814825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:08.878314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.770999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:33:08.878449Z","caller":"traceutil/trace.go:171","msg":"trace[103634579] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"230.574713ms","start":"2026-05-21T03:33:08.647847Z","end":"2026-05-21T03:33:08.878421Z","steps":["trace[103634579] 'process raft request' (duration: 122.584309ms)","trace[103634579] 'compare' (duration: 107.648266ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:09.570317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.168384ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:33:09.57047Z","caller":"traceutil/trace.go:171","msg":"trace[403815754] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1338; }","duration":"281.342798ms","start":"2026-05-21T03:33:09.289108Z","end":"2026-05-21T03:33:09.57045Z","steps":["trace[403815754] 'range keys from in-memory index tree' (duration: 281.145954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:09.570801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.185734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:33:09.570862Z","caller":"traceutil/trace.go:171","msg":"trace[1677260338] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"638.408086ms","start":"2026-05-21T03:33:08.932439Z","end":"2026-05-21T03:33:09.570847Z","steps":["trace[1677260338] 'read index received' (duration: 55.040477ms)","trace[1677260338] 'applied index is now lower than readState.Index' (duration: 583.366599ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:33:09.570932Z","caller":"traceutil/trace.go:171","msg":"trace[1953248317] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"724.261043ms","start":"2026-05-21T03:33:08.846649Z","end":"2026-05-21T03:33:09.57091Z","steps":["trace[1953248317] 'process raft request' (duration: 140.915835ms)","trace[1953248317] 'compare' (duration: 582.929418ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:09.570981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:08.846554Z","time spent":"724.404676ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:33:09.571143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.729893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:33:09.571236Z","caller":"traceutil/trace.go:171","msg":"trace[49980242] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1339; }","duration":"638.865916ms","start":"2026-05-21T03:33:08.932349Z","end":"2026-05-21T03:33:09.571215Z","steps":["trace[49980242] 'agreement among raft nodes before linearized reading' (duration: 638.676662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:09.571277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:08.932331Z","time spent":"638.935568ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T03:33:10.084064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.986413ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T03:33:10.08438Z","caller":"traceutil/trace.go:171","msg":"trace[1136167581] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1392; }","duration":"513.464779ms","start":"2026-05-21T03:33:09.57088Z","end":"2026-05-21T03:33:10.084345Z","steps":["trace[1136167581] 'read index received' (duration: 4.051886ms)","trace[1136167581] 'applied index is now lower than readState.Index' (duration: 509.409212ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:33:10.084784Z","caller":"traceutil/trace.go:171","msg":"trace[641861266] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"509.039664ms","start":"2026-05-21T03:33:09.575721Z","end":"2026-05-21T03:33:10.08476Z","steps":["trace[641861266] 'process raft request' (duration: 508.461501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:10.084923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:09.575698Z","time spent":"509.153317ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:33:10.347556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.940552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:33:10.347683Z","caller":"traceutil/trace.go:171","msg":"trace[2134460674] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1340; }","duration":"822.075065ms","start":"2026-05-21T03:33:09.525546Z","end":"2026-05-21T03:33:10.347621Z","steps":["trace[2134460674] 'agreement among raft nodes before linearized reading' (duration: 558.934578ms)","trace[2134460674] 'count revisions from in-memory index tree' (duration: 263.018025ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:10.348063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:09.525524Z","time spent":"822.472925ms","remote":"127.0.0.1:47582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-21T03:33:10.367276Z","caller":"traceutil/trace.go:171","msg":"trace[1974422702] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"282.815494ms","start":"2026-05-21T03:33:10.084449Z","end":"2026-05-21T03:33:10.367264Z","steps":["trace[1974422702] 'read index received' (duration: 282.62225ms)","trace[1974422702] 'applied index is now lower than readState.Index' (duration: 192.544µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:33:10.36734Z","caller":"traceutil/trace.go:171","msg":"trace[800601562] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"791.569891ms","start":"2026-05-21T03:33:09.575748Z","end":"2026-05-21T03:33:10.367318Z","steps":["trace[800601562] 'process raft request' (duration: 791.285655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:10.367467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:09.575721Z","time spent":"791.674564ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:33:10.367501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.145781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:33:10.367683Z","caller":"traceutil/trace.go:171","msg":"trace[1180812339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"679.307425ms","start":"2026-05-21T03:33:09.688306Z","end":"2026-05-21T03:33:10.367614Z","steps":["trace[1180812339] 'agreement among raft nodes before linearized reading' (duration: 679.117911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:10.367819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:09.688286Z","time spent":"679.516461ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:33:10.374943Z","caller":"traceutil/trace.go:171","msg":"trace[2064351149] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"284.047713ms","start":"2026-05-21T03:33:10.09088Z","end":"2026-05-21T03:33:10.374927Z","steps":["trace[2064351149] 'process raft request' (duration: 283.955611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:10.375118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.740243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-21T03:33:10.375252Z","caller":"traceutil/trace.go:171","msg":"trace[720329415] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1342; }","duration":"285.917218ms","start":"2026-05-21T03:33:10.089323Z","end":"2026-05-21T03:33:10.37524Z","steps":["trace[720329415] 'agreement among raft nodes before linearized reading' (duration: 285.60809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:10.718517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.381377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:33:10.718934Z","caller":"traceutil/trace.go:171","msg":"trace[833392217] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"337.453751ms","start":"2026-05-21T03:33:10.381461Z","end":"2026-05-21T03:33:10.718914Z","steps":["trace[833392217] 'process raft request' (duration: 227.601283ms)","trace[833392217] 'compare' (duration: 109.210133ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:33:10.719022Z","caller":"traceutil/trace.go:171","msg":"trace[76074061] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1397; }","duration":"291.708655ms","start":"2026-05-21T03:33:10.427246Z","end":"2026-05-21T03:33:10.718954Z","steps":["trace[76074061] 'read index received' (duration: 181.852417ms)","trace[76074061] 'applied index is now lower than readState.Index' (duration: 109.854038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:10.719176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.245321ms","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-05-21T03:33:10.719221Z","caller":"traceutil/trace.go:171","msg":"trace[1360329383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"293.329543ms","start":"2026-05-21T03:33:10.425878Z","end":"2026-05-21T03:33:10.719208Z","steps":["trace[1360329383] 'agreement among raft nodes before linearized reading' (duration: 293.221121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:10.719179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.584367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:33:10.719317Z","caller":"traceutil/trace.go:171","msg":"trace[1877367128] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1345; }","duration":"247.746571ms","start":"2026-05-21T03:33:10.471554Z","end":"2026-05-21T03:33:10.719301Z","steps":["trace[1877367128] 'agreement among raft nodes before linearized reading' (duration: 247.586488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:10.719486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.229437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:33:10.719748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:10.381445Z","time spent":"337.599834ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:10.719928Z","caller":"traceutil/trace.go:171","msg":"trace[899231407] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1345; }","duration":"241.75603ms","start":"2026-05-21T03:33:10.478152Z","end":"2026-05-21T03:33:10.719908Z","steps":["trace[899231407] 'agreement among raft nodes before linearized reading' (duration: 241.213587ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:10.851238Z","caller":"traceutil/trace.go:171","msg":"trace[1075256437] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"126.716008ms","start":"2026-05-21T03:33:10.724494Z","end":"2026-05-21T03:33:10.85121Z","steps":["trace[1075256437] 'process raft request' (duration: 125.55551ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:10.852123Z","caller":"traceutil/trace.go:171","msg":"trace[910009836] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"127.041896ms","start":"2026-05-21T03:33:10.725066Z","end":"2026-05-21T03:33:10.852108Z","steps":["trace[910009836] 'process raft request' (duration: 126.952004ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:10.852338Z","caller":"traceutil/trace.go:171","msg":"trace[1318604091] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"127.403494ms","start":"2026-05-21T03:33:10.724885Z","end":"2026-05-21T03:33:10.852288Z","steps":["trace[1318604091] 'process raft request' (duration: 127.053706ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:10.852663Z","caller":"traceutil/trace.go:171","msg":"trace[1711627139] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"127.041236ms","start":"2026-05-21T03:33:10.725609Z","end":"2026-05-21T03:33:10.85265Z","steps":["trace[1711627139] 'process raft request' (duration: 126.933083ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:10.852661Z","caller":"traceutil/trace.go:171","msg":"trace[1912686007] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"127.079747ms","start":"2026-05-21T03:33:10.725561Z","end":"2026-05-21T03:33:10.85264Z","steps":["trace[1912686007] 'process raft request' (duration: 126.506003ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:10.852651Z","caller":"traceutil/trace.go:171","msg":"trace[1610675284] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1399; }","duration":"103.886347ms","start":"2026-05-21T03:33:10.748742Z","end":"2026-05-21T03:33:10.852628Z","steps":["trace[1610675284] 'read index received' (duration: 101.404548ms)","trace[1610675284] 'applied index is now lower than readState.Index' (duration: 2.480899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:10.853066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.294606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T03:33:10.853109Z","caller":"traceutil/trace.go:171","msg":"trace[218655524] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1350; }","duration":"104.366428ms","start":"2026-05-21T03:33:10.748731Z","end":"2026-05-21T03:33:10.853097Z","steps":["trace[218655524] 'agreement among raft nodes before linearized reading' (duration: 104.154013ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:12.86939Z","caller":"traceutil/trace.go:171","msg":"trace[902014461] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"142.313839ms","start":"2026-05-21T03:33:12.727056Z","end":"2026-05-21T03:33:12.86937Z","steps":["trace[902014461] 'process raft request' (duration: 142.074963ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:14.509073Z","caller":"traceutil/trace.go:171","msg":"trace[1103167058] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"619.839655ms","start":"2026-05-21T03:33:13.889207Z","end":"2026-05-21T03:33:14.509046Z","steps":["trace[1103167058] 'process raft request' (duration: 619.707582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:14.509245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:13.88919Z","time spent":"619.949308ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:33:15.513479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.1392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:33:15.513644Z","caller":"traceutil/trace.go:171","msg":"trace[376021439] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"637.431423ms","start":"2026-05-21T03:33:14.876197Z","end":"2026-05-21T03:33:15.513628Z","steps":["trace[376021439] 'process raft request' (duration: 637.373212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.513707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:14.876181Z","time spent":"637.494554ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:33:15.51372Z","caller":"traceutil/trace.go:171","msg":"trace[1879341992] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1417; }","duration":"1.18997272s","start":"2026-05-21T03:33:14.32373Z","end":"2026-05-21T03:33:15.513703Z","steps":["trace[1879341992] 'read index received' (duration: 185.250188ms)","trace[1879341992] 'applied index is now lower than readState.Index' (duration: 1.004721172s)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:33:15.513797Z","caller":"traceutil/trace.go:171","msg":"trace[544402632] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"1.598369675s","start":"2026-05-21T03:33:13.915419Z","end":"2026-05-21T03:33:15.513789Z","steps":["trace[544402632] 'process raft request' (duration: 1.218841835s)","trace[544402632] 'compare' (duration: 378.68474ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:33:15.514013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:13.915402Z","time spent":"1.59858501s","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:33:15.514164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.884286ms","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-05-21T03:33:15.514197Z","caller":"traceutil/trace.go:171","msg":"trace[1965895395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1366; }","duration":"619.927857ms","start":"2026-05-21T03:33:14.894261Z","end":"2026-05-21T03:33:15.514188Z","steps":["trace[1965895395] 'agreement among raft nodes before linearized reading' (duration: 619.835405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.514222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:14.894253Z","time spent":"619.962808ms","remote":"127.0.0.1:39298","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-05-21T03:33:15.514283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.752865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T03:33:15.514363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.356057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:33:15.514388Z","caller":"traceutil/trace.go:171","msg":"trace[34436593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"633.889658ms","start":"2026-05-21T03:33:14.880475Z","end":"2026-05-21T03:33:15.514365Z","steps":["trace[34436593] 'agreement among raft nodes before linearized reading' (duration: 633.560951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.514418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.47058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T03:33:15.514457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:14.880457Z","time spent":"633.985571ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T03:33:15.514487Z","caller":"traceutil/trace.go:171","msg":"trace[798637784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1366; }","duration":"620.548112ms","start":"2026-05-21T03:33:14.893925Z","end":"2026-05-21T03:33:15.514473Z","steps":["trace[798637784] 'agreement among raft nodes before linearized reading' (duration: 620.409779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.514548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:14.893918Z","time spent":"620.620924ms","remote":"127.0.0.1:39298","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-05-21T03:33:15.514283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.500504ms","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-05-21T03:33:15.514396Z","caller":"traceutil/trace.go:171","msg":"trace[144438219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"326.385989ms","start":"2026-05-21T03:33:15.188002Z","end":"2026-05-21T03:33:15.514388Z","steps":["trace[144438219] 'agreement among raft nodes before linearized reading' (duration: 326.346688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.514608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:15.187941Z","time spent":"326.658335ms","remote":"127.0.0.1:39002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T03:33:15.514607Z","caller":"traceutil/trace.go:171","msg":"trace[174256353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1366; }","duration":"621.866553ms","start":"2026-05-21T03:33:14.89272Z","end":"2026-05-21T03:33:15.514586Z","steps":["trace[174256353] 'agreement among raft nodes before linearized reading' (duration: 621.395042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.514332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190610275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-21T03:33:15.514694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:14.892703Z","time spent":"621.952215ms","remote":"127.0.0.1:39298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:33:15.514626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.507399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:33:15.514747Z","caller":"traceutil/trace.go:171","msg":"trace[559371738] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:1366; }","duration":"1.191037995s","start":"2026-05-21T03:33:14.323677Z","end":"2026-05-21T03:33:15.514715Z","steps":["trace[559371738] 'agreement among raft nodes before linearized reading' (duration: 1.190602554s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.514865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:14.323655Z","time spent":"1.191174308s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"info","ts":"2026-05-21T03:33:15.514876Z","caller":"traceutil/trace.go:171","msg":"trace[714345761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1366; }","duration":"633.753345ms","start":"2026-05-21T03:33:14.881055Z","end":"2026-05-21T03:33:15.514809Z","steps":["trace[714345761] 'agreement among raft nodes before linearized reading' (duration: 633.09994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:33:15.515022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:33:14.881007Z","time spent":"633.996571ms","remote":"127.0.0.1:39298","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-05-21T03:33:15.648356Z","caller":"traceutil/trace.go:171","msg":"trace[1138165454] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"128.516781ms","start":"2026-05-21T03:33:15.519814Z","end":"2026-05-21T03:33:15.64833Z","steps":["trace[1138165454] 'process raft request' (duration: 128.167323ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:15.648373Z","caller":"traceutil/trace.go:171","msg":"trace[17883295] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"128.015779ms","start":"2026-05-21T03:33:15.520331Z","end":"2026-05-21T03:33:15.648347Z","steps":["trace[17883295] 'process raft request' (duration: 127.913527ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:15.648735Z","caller":"traceutil/trace.go:171","msg":"trace[750548670] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"128.06019ms","start":"2026-05-21T03:33:15.520651Z","end":"2026-05-21T03:33:15.648711Z","steps":["trace[750548670] 'process raft request' (duration: 127.64601ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:15.649222Z","caller":"traceutil/trace.go:171","msg":"trace[1378330845] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"128.158642ms","start":"2026-05-21T03:33:15.521049Z","end":"2026-05-21T03:33:15.649207Z","steps":["trace[1378330845] 'process raft request' (duration: 127.480486ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:33:15.649282Z","caller":"traceutil/trace.go:171","msg":"trace[1931179816] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"128.159912ms","start":"2026-05-21T03:33:15.521103Z","end":"2026-05-21T03:33:15.649263Z","steps":["trace[1931179816] 'process raft request' (duration: 128.06775ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:34:44.292404Z","caller":"traceutil/trace.go:171","msg":"trace[398907321] transaction","detail":"{read_only:false; response_revision:1791; number_of_response:1; }","duration":"120.782742ms","start":"2026-05-21T03:34:44.171574Z","end":"2026-05-21T03:34:44.292356Z","steps":["trace[398907321] 'process raft request' (duration: 93.240475ms)","trace[398907321] 'compare' (duration: 27.366733ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:34:44.292508Z","caller":"traceutil/trace.go:171","msg":"trace[1727442652] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1862; }","duration":"118.863426ms","start":"2026-05-21T03:34:44.173616Z","end":"2026-05-21T03:34:44.292479Z","steps":["trace[1727442652] 'read index received' (duration: 91.224237ms)","trace[1727442652] 'applied index is now lower than readState.Index' (duration: 27.637999ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:34:44.292618Z","caller":"traceutil/trace.go:171","msg":"trace[1940101444] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"119.767897ms","start":"2026-05-21T03:34:44.172838Z","end":"2026-05-21T03:34:44.292606Z","steps":["trace[1940101444] 'process raft request' (duration: 119.4742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:34:44.292742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.01784ms","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-05-21T03:34:44.292815Z","caller":"traceutil/trace.go:171","msg":"trace[611182061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1792; }","duration":"119.127612ms","start":"2026-05-21T03:34:44.173672Z","end":"2026-05-21T03:34:44.292799Z","steps":["trace[611182061] 'agreement among raft nodes before linearized reading' (duration: 118.968868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:34:44.29287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.234715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T03:34:44.29291Z","caller":"traceutil/trace.go:171","msg":"trace[1975312623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1792; }","duration":"119.300606ms","start":"2026-05-21T03:34:44.1736Z","end":"2026-05-21T03:34:44.2929Z","steps":["trace[1975312623] 'agreement among raft nodes before linearized reading' (duration: 119.152303ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:34:54.509792Z","caller":"traceutil/trace.go:171","msg":"trace[1876785582] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"124.854359ms","start":"2026-05-21T03:34:54.384892Z","end":"2026-05-21T03:34:54.509746Z","steps":["trace[1876785582] 'process raft request' (duration: 54.015679ms)","trace[1876785582] 'compare' (duration: 70.476811ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:34:54.509852Z","caller":"traceutil/trace.go:171","msg":"trace[1816339628] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1911; }","duration":"102.307731ms","start":"2026-05-21T03:34:54.407501Z","end":"2026-05-21T03:34:54.509809Z","steps":["trace[1816339628] 'read index received' (duration: 31.420459ms)","trace[1816339628] 'applied index is now lower than readState.Index' (duration: 70.885571ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:34:54.510044Z","caller":"traceutil/trace.go:171","msg":"trace[1489118626] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"122.690568ms","start":"2026-05-21T03:34:54.387335Z","end":"2026-05-21T03:34:54.510025Z","steps":["trace[1489118626] 'process raft request' (duration: 122.37122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:34:54.510425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.909546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-21T03:34:54.510496Z","caller":"traceutil/trace.go:171","msg":"trace[1375310198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1838; }","duration":"103.046118ms","start":"2026-05-21T03:34:54.407422Z","end":"2026-05-21T03:34:54.510468Z","steps":["trace[1375310198] 'agreement among raft nodes before linearized reading' (duration: 102.825733ms)"],"step_count":1}