{"level":"warn","ts":"2026-05-07T13:44:16.059593Z","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-07T13:44:16.059689Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-07T13:44:16.059761Z","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-07T13:44:16.059776Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-07T13:44:16.059797Z","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-07T13:44:16.060456Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-07T13:44:16.060543Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-07T13:44:16.065153Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.416077ms"} {"level":"info","ts":"2026-05-07T13:44:16.075009Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-07T13:44:16.075093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T13:44:16.075115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-07T13:44:16.075128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T13:44:16.075139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-07T13:44:16.075185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-07T13:44:16.083973Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T13:44:16.085923Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T13:44:16.087861Z","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-07T13:44:16.091183Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T13:44:16.091448Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T13:44:16.091642Z","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-07T13:44:16.091788Z","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-07T13:44:16.091851Z","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-07T13:44:16.094152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-07T13:44:16.094466Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-07T13:44:16.096095Z","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-07T13:44:16.096814Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-07T13:44:16.09685Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-07T13:44:16.097848Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T13:44:16.097907Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T13:44:16.175342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T13:44:16.175391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T13:44:16.175411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-07T13:44:16.175428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-07T13:44:16.175545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-07T13:44:16.175635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-07T13:44:16.175658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-07T13:44:16.178465Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:44:16.185835Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T13:44:16.185889Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:44:16.186032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:44:16.186217Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T13:44:16.186241Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T13:44:16.186276Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:44:16.186411Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:44:16.186449Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:44:16.189836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"info","ts":"2026-05-07T13:44:16.189882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-07T13:44:22.292278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.088075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-07T13:44:22.29237Z","caller":"traceutil/trace.go:171","msg":"trace[1060127065] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:221; }","duration":"104.186076ms","start":"2026-05-07T13:44:22.188167Z","end":"2026-05-07T13:44:22.292353Z","steps":["trace[1060127065] 'range keys from in-memory index tree' (duration: 104.009435ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:22.504293Z","caller":"traceutil/trace.go:171","msg":"trace[1129884646] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"205.705241ms","start":"2026-05-07T13:44:22.298563Z","end":"2026-05-07T13:44:22.504268Z","steps":["trace[1129884646] 'process raft request' (duration: 205.617441ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:22.504458Z","caller":"traceutil/trace.go:171","msg":"trace[266173479] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"208.236773ms","start":"2026-05-07T13:44:22.29607Z","end":"2026-05-07T13:44:22.504306Z","steps":["trace[266173479] 'process raft request' (duration: 184.274065ms)","trace[266173479] 'compare' (duration: 23.514124ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:22.996576Z","caller":"traceutil/trace.go:171","msg":"trace[22231822] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"156.954931ms","start":"2026-05-07T13:44:22.839593Z","end":"2026-05-07T13:44:22.996548Z","steps":["trace[22231822] 'process raft request' (duration: 132.559211ms)","trace[22231822] 'compare' (duration: 24.249668ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:23.194899Z","caller":"traceutil/trace.go:171","msg":"trace[1068680749] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:239; }","duration":"123.669262ms","start":"2026-05-07T13:44:23.071211Z","end":"2026-05-07T13:44:23.194881Z","steps":["trace[1068680749] 'read index received' (duration: 61.407802ms)","trace[1068680749] 'applied index is now lower than readState.Index' (duration: 62.25941ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:23.195134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.891284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:23.194982Z","caller":"traceutil/trace.go:171","msg":"trace[52261664] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"125.310896ms","start":"2026-05-07T13:44:23.069654Z","end":"2026-05-07T13:44:23.194965Z","steps":["trace[52261664] 'process raft request' (duration: 125.129684ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:23.19522Z","caller":"traceutil/trace.go:171","msg":"trace[29134824] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:0; response_revision:235; }","duration":"124.024405ms","start":"2026-05-07T13:44:23.071177Z","end":"2026-05-07T13:44:23.195201Z","steps":["trace[29134824] 'agreement among raft nodes before linearized reading' (duration: 123.800334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:23.382739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.222509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:44:23.382928Z","caller":"traceutil/trace.go:171","msg":"trace[1520180853] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"179.684131ms","start":"2026-05-07T13:44:23.203227Z","end":"2026-05-07T13:44:23.382911Z","steps":["trace[1520180853] 'process raft request' (duration: 179.624761ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:23.383141Z","caller":"traceutil/trace.go:171","msg":"trace[761189984] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"180.89792ms","start":"2026-05-07T13:44:23.202138Z","end":"2026-05-07T13:44:23.383035Z","steps":["trace[761189984] 'process raft request' (duration: 56.888205ms)","trace[761189984] 'compare' (duration: 123.050648ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:23.388099Z","caller":"traceutil/trace.go:171","msg":"trace[1642332813] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"115.941449ms","start":"2026-05-07T13:44:23.272129Z","end":"2026-05-07T13:44:23.38807Z","steps":["trace[1642332813] 'process raft request' (duration: 115.648276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:23.778573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.585213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:44:23.778741Z","caller":"traceutil/trace.go:171","msg":"trace[19320491] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"247.83268ms","start":"2026-05-07T13:44:23.530888Z","end":"2026-05-07T13:44:23.778721Z","steps":["trace[19320491] 'process raft request' (duration: 247.778099ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:23.778779Z","caller":"traceutil/trace.go:171","msg":"trace[687657989] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"391.454355ms","start":"2026-05-07T13:44:23.387297Z","end":"2026-05-07T13:44:23.778751Z","steps":["trace[687657989] 'process raft request' (duration: 96.62379ms)","trace[687657989] 'compare' (duration: 294.447932ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:23.778784Z","caller":"traceutil/trace.go:171","msg":"trace[1599581125] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:245; }","duration":"388.624052ms","start":"2026-05-07T13:44:23.390134Z","end":"2026-05-07T13:44:23.778758Z","steps":["trace[1599581125] 'read index received' (duration: 93.747877ms)","trace[1599581125] 'applied index is now lower than readState.Index' (duration: 294.873125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:23.778919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:23.387271Z","time spent":"391.601637ms","remote":"127.0.0.1:56554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":290,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:44:23.78418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.035116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-07T13:44:23.784234Z","caller":"traceutil/trace.go:171","msg":"trace[1111203229] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:241; }","duration":"394.117977ms","start":"2026-05-07T13:44:23.390097Z","end":"2026-05-07T13:44:23.784215Z","steps":["trace[1111203229] 'agreement among raft nodes before linearized reading' (duration: 388.872404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:23.78426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:23.390081Z","time spent":"394.170498ms","remote":"127.0.0.1:56330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-07T13:44:23.78454Z","caller":"traceutil/trace.go:171","msg":"trace[509965408] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"245.481551ms","start":"2026-05-07T13:44:23.539006Z","end":"2026-05-07T13:44:23.784487Z","steps":["trace[509965408] 'process raft request' (duration: 244.468822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:23.784715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.091698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:44:23.784774Z","caller":"traceutil/trace.go:171","msg":"trace[1575081818] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:242; }","duration":"322.190878ms","start":"2026-05-07T13:44:23.462561Z","end":"2026-05-07T13:44:23.784752Z","steps":["trace[1575081818] 'agreement among raft nodes before linearized reading' (duration: 322.049267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:23.784813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:23.462537Z","time spent":"322.265708ms","remote":"127.0.0.1:56462","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-07T13:44:23.785132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.853049ms","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-07T13:44:23.785174Z","caller":"traceutil/trace.go:171","msg":"trace[667885554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:242; }","duration":"286.949569ms","start":"2026-05-07T13:44:23.498212Z","end":"2026-05-07T13:44:23.785162Z","steps":["trace[667885554] 'agreement among raft nodes before linearized reading' (duration: 286.866509ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:23.968285Z","caller":"traceutil/trace.go:171","msg":"trace[1067650984] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"114.185934ms","start":"2026-05-07T13:44:23.854074Z","end":"2026-05-07T13:44:23.96826Z","steps":["trace[1067650984] 'process raft request' (duration: 44.921077ms)","trace[1067650984] 'compare' (duration: 68.817434ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:23.968545Z","caller":"traceutil/trace.go:171","msg":"trace[788799106] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"113.420148ms","start":"2026-05-07T13:44:23.85511Z","end":"2026-05-07T13:44:23.96853Z","steps":["trace[788799106] 'read index received' (duration: 43.9608ms)","trace[788799106] 'applied index is now lower than readState.Index' (duration: 69.457808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:23.968675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.53767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslicemirroring-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:23.968733Z","caller":"traceutil/trace.go:171","msg":"trace[1807709509] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslicemirroring-controller; range_end:; response_count:0; response_revision:252; }","duration":"113.655011ms","start":"2026-05-07T13:44:23.855058Z","end":"2026-05-07T13:44:23.968713Z","steps":["trace[1807709509] 'agreement among raft nodes before linearized reading' (duration: 113.523449ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:23.96883Z","caller":"traceutil/trace.go:171","msg":"trace[1319851721] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"111.719255ms","start":"2026-05-07T13:44:23.857066Z","end":"2026-05-07T13:44:23.968786Z","steps":["trace[1319851721] 'process raft request' (duration: 110.899188ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:24.101533Z","caller":"traceutil/trace.go:171","msg":"trace[806841335] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"128.969603ms","start":"2026-05-07T13:44:23.972522Z","end":"2026-05-07T13:44:24.101492Z","steps":["trace[806841335] 'process raft request' (duration: 127.315699ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:24.101616Z","caller":"traceutil/trace.go:171","msg":"trace[799168755] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"126.346271ms","start":"2026-05-07T13:44:23.975258Z","end":"2026-05-07T13:44:24.101604Z","steps":["trace[799168755] 'process raft request' (duration: 126.129309ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:24.101537Z","caller":"traceutil/trace.go:171","msg":"trace[1867608428] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"122.362869ms","start":"2026-05-07T13:44:23.979144Z","end":"2026-05-07T13:44:24.101507Z","steps":["trace[1867608428] 'process raft request' (duration: 122.300069ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:25.742172Z","caller":"traceutil/trace.go:171","msg":"trace[1501985643] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"211.212315ms","start":"2026-05-07T13:44:25.530934Z","end":"2026-05-07T13:44:25.742146Z","steps":["trace[1501985643] 'read index received' (duration: 177.228183ms)","trace[1501985643] 'applied index is now lower than readState.Index' (duration: 33.983082ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:25.742194Z","caller":"traceutil/trace.go:171","msg":"trace[1618494703] transaction","detail":"{read_only:false; number_of_response:0; response_revision:329; }","duration":"211.898392ms","start":"2026-05-07T13:44:25.530268Z","end":"2026-05-07T13:44:25.742167Z","steps":["trace[1618494703] 'process raft request' (duration: 177.767559ms)","trace[1618494703] 'compare' (duration: 34.056432ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:25.742365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.412097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-05-07T13:44:25.742396Z","caller":"traceutil/trace.go:171","msg":"trace[1847545951] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:329; }","duration":"211.489378ms","start":"2026-05-07T13:44:25.5309Z","end":"2026-05-07T13:44:25.742389Z","steps":["trace[1847545951] 'agreement among raft nodes before linearized reading' (duration: 211.339786ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:25.843902Z","caller":"traceutil/trace.go:171","msg":"trace[194396448] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"101.631867ms","start":"2026-05-07T13:44:25.742249Z","end":"2026-05-07T13:44:25.843881Z","steps":["trace[194396448] 'read index received' (duration: 101.585907ms)","trace[194396448] 'applied index is now lower than readState.Index' (duration: 45.17µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:25.843903Z","caller":"traceutil/trace.go:171","msg":"trace[1898131464] transaction","detail":"{read_only:false; number_of_response:0; response_revision:329; }","duration":"312.409297ms","start":"2026-05-07T13:44:25.531479Z","end":"2026-05-07T13:44:25.843888Z","steps":["trace[1898131464] 'process raft request' (duration: 312.329736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:25.844044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.223218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-07T13:44:25.844112Z","caller":"traceutil/trace.go:171","msg":"trace[808123900] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:329; }","duration":"306.313178ms","start":"2026-05-07T13:44:25.537781Z","end":"2026-05-07T13:44:25.844094Z","steps":["trace[808123900] 'agreement among raft nodes before linearized reading' (duration: 306.183747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:25.844152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:25.537773Z","time spent":"306.374329ms","remote":"127.0.0.1:56392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-05-07T13:44:25.844123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:25.531467Z","time spent":"312.577349ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:44:25.884407Z","caller":"traceutil/trace.go:171","msg":"trace[2137046735] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"121.977035ms","start":"2026-05-07T13:44:25.762404Z","end":"2026-05-07T13:44:25.884381Z","steps":["trace[2137046735] 'process raft request' (duration: 121.847304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:25.88486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.673106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"info","ts":"2026-05-07T13:44:25.884927Z","caller":"traceutil/trace.go:171","msg":"trace[1220200044] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:330; }","duration":"141.803418ms","start":"2026-05-07T13:44:25.74311Z","end":"2026-05-07T13:44:25.884913Z","steps":["trace[1220200044] 'agreement among raft nodes before linearized reading' (duration: 141.660646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:31.769593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.911188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-07T13:44:31.769721Z","caller":"traceutil/trace.go:171","msg":"trace[1344579081] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:401; }","duration":"225.073063ms","start":"2026-05-07T13:44:31.544611Z","end":"2026-05-07T13:44:31.769684Z","steps":["trace[1344579081] 'range keys from in-memory index tree' (duration: 224.620448ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:32.0689Z","caller":"traceutil/trace.go:171","msg":"trace[1301058869] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"288.688896ms","start":"2026-05-07T13:44:31.780176Z","end":"2026-05-07T13:44:32.068865Z","steps":["trace[1301058869] 'process raft request' (duration: 288.49389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:32.391596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.354992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:44:32.39185Z","caller":"traceutil/trace.go:171","msg":"trace[1480481183] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:416; }","duration":"317.960899ms","start":"2026-05-07T13:44:32.073871Z","end":"2026-05-07T13:44:32.391831Z","steps":["trace[1480481183] 'read index received' (duration: 65.110341ms)","trace[1480481183] 'applied index is now lower than readState.Index' (duration: 252.849668ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:32.391858Z","caller":"traceutil/trace.go:171","msg":"trace[545630245] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"318.449875ms","start":"2026-05-07T13:44:32.07339Z","end":"2026-05-07T13:44:32.391839Z","steps":["trace[545630245] 'process raft request' (duration: 318.30743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:32.392003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.130425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"warn","ts":"2026-05-07T13:44:32.392005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:32.073371Z","time spent":"318.550868ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:44:32.392049Z","caller":"traceutil/trace.go:171","msg":"trace[1796775868] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:405; }","duration":"318.208317ms","start":"2026-05-07T13:44:32.073831Z","end":"2026-05-07T13:44:32.392039Z","steps":["trace[1796775868] 'agreement among raft nodes before linearized reading' (duration: 318.069053ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:32.391887Z","caller":"traceutil/trace.go:171","msg":"trace[2045664829] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"318.208087ms","start":"2026-05-07T13:44:32.073649Z","end":"2026-05-07T13:44:32.391857Z","steps":["trace[2045664829] 'process raft request' (duration: 318.143815ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:32.392167Z","caller":"traceutil/trace.go:171","msg":"trace[1006055432] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"318.615681ms","start":"2026-05-07T13:44:32.073182Z","end":"2026-05-07T13:44:32.391798Z","steps":["trace[1006055432] 'process raft request' (duration: 65.787023ms)","trace[1006055432] 'compare' (duration: 252.072103ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:32.392085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:32.07382Z","time spent":"318.255269ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5358,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-05-07T13:44:32.39224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:32.073157Z","time spent":"319.049724ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:32.392302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:32.073625Z","time spent":"318.541518ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:34.914914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930834610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:44:35.118349Z","caller":"traceutil/trace.go:171","msg":"trace[721325645] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"708.921ms","start":"2026-05-07T13:44:34.409403Z","end":"2026-05-07T13:44:35.118324Z","steps":["trace[721325645] 'process raft request' (duration: 708.772585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:35.11849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:34.409386Z","time spent":"709.030993ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:35.606344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.217143ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:44:35.606465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.533583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:44:35.606549Z","caller":"traceutil/trace.go:171","msg":"trace[792212587] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:410; }","duration":"249.452261ms","start":"2026-05-07T13:44:35.357063Z","end":"2026-05-07T13:44:35.606515Z","steps":["trace[792212587] 'range keys from in-memory index tree' (duration: 249.181813ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:35.606764Z","caller":"traceutil/trace.go:171","msg":"trace[1621376801] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.197233627s","start":"2026-05-07T13:44:34.409478Z","end":"2026-05-07T13:44:35.606712Z","steps":["trace[1621376801] 'process raft request' (duration: 1.057350663s)","trace[1621376801] 'compare' (duration: 139.408269ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:35.606984Z","caller":"traceutil/trace.go:171","msg":"trace[1301362745] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"1.196755973s","start":"2026-05-07T13:44:34.4102Z","end":"2026-05-07T13:44:35.606956Z","steps":["trace[1301362745] 'process raft request' (duration: 1.196385601s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:35.60699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:34.409457Z","time spent":"1.197416573s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:35.607065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:34.41019Z","time spent":"1.196835445s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:36.414182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99999014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T13:44:36.414265Z","caller":"traceutil/trace.go:171","msg":"trace[1263663917] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000105884s","start":"2026-05-07T13:44:34.414138Z","end":"2026-05-07T13:44:36.414244Z","steps":["trace[1263663917] 'agreement among raft nodes before linearized reading' (duration: 2.000001741s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:36.414302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:34.41413Z","time spent":"2.000165385s","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:44:36.757231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150564694s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-07T13:44:36.757484Z","caller":"traceutil/trace.go:171","msg":"trace[280696707] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:423; }","duration":"2.343305309s","start":"2026-05-07T13:44:34.414164Z","end":"2026-05-07T13:44:36.75747Z","steps":["trace[280696707] 'read index received' (duration: 704.085057ms)","trace[280696707] 'applied index is now lower than readState.Index' (duration: 1.639219612s)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:36.757733Z","caller":"traceutil/trace.go:171","msg":"trace[891078717] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"2.121935369s","start":"2026-05-07T13:44:34.635785Z","end":"2026-05-07T13:44:36.75772Z","steps":["trace[891078717] 'process raft request' (duration: 2.121551997s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:36.75784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:34.635762Z","time spent":"2.122019321s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:36.757989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.443266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:36.758035Z","caller":"traceutil/trace.go:171","msg":"trace[1743438231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"280.527258ms","start":"2026-05-07T13:44:36.477495Z","end":"2026-05-07T13:44:36.758022Z","steps":["trace[1743438231] 'agreement among raft nodes before linearized reading' (duration: 280.431675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:36.758063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460957097s","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-07T13:44:36.758114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.262557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:36.758141Z","caller":"traceutil/trace.go:171","msg":"trace[321296969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"1.461079112s","start":"2026-05-07T13:44:35.297039Z","end":"2026-05-07T13:44:36.758119Z","steps":["trace[321296969] 'agreement among raft nodes before linearized reading' (duration: 1.460869985s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:36.758178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:35.297018Z","time spent":"1.461153584s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T13:44:36.758175Z","caller":"traceutil/trace.go:171","msg":"trace[621177539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"343.376841ms","start":"2026-05-07T13:44:36.414779Z","end":"2026-05-07T13:44:36.758156Z","steps":["trace[621177539] 'agreement among raft nodes before linearized reading' (duration: 343.269868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:36.758217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:36.414758Z","time spent":"343.449963ms","remote":"127.0.0.1:56208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:44:37.62235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930834617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:44:38.122652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930834617,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:44:38.322181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.560902219s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T13:44:38.61471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.245884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:44:38.614812Z","caller":"traceutil/trace.go:171","msg":"trace[2012527477] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.853572856s","start":"2026-05-07T13:44:36.761222Z","end":"2026-05-07T13:44:38.614794Z","steps":["trace[2012527477] 'process raft request' (duration: 1.561181877s)","trace[2012527477] 'compare' (duration: 291.919415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:38.61487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:36.761206Z","time spent":"1.853636158s","remote":"127.0.0.1:56284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:44:39.418206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999768641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-07T13:44:39.419343Z","caller":"traceutil/trace.go:171","msg":"trace[555233182] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0004059s","start":"2026-05-07T13:44:37.418333Z","end":"2026-05-07T13:44:39.418739Z","steps":["trace[555233182] 'agreement among raft nodes before linearized reading' (duration: 1.999803071s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:39.419518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:37.418305Z","time spent":"2.001179524s","remote":"127.0.0.1:56208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/07 13:44:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-07T13:44:39.498349Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.175909841s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T13:44:39.805255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.629977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:44:39.805458Z","caller":"traceutil/trace.go:171","msg":"trace[1825667874] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:428; }","duration":"2.683186226s","start":"2026-05-07T13:44:37.122251Z","end":"2026-05-07T13:44:39.805437Z","steps":["trace[1825667874] 'read index received' (duration: 1.200163343s)","trace[1825667874] 'applied index is now lower than readState.Index' (duration: 1.483021503s)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:39.805563Z","caller":"traceutil/trace.go:171","msg":"trace[1031588243] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"3.042394794s","start":"2026-05-07T13:44:36.763156Z","end":"2026-05-07T13:44:39.805551Z","steps":["trace[1031588243] 'process raft request' (duration: 2.735379675s)","trace[1031588243] 'compare' (duration: 306.324627ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:39.805634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:36.763137Z","time spent":"3.042461065s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:44:39.80591Z","caller":"traceutil/trace.go:171","msg":"trace[410497235] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.537663943s","start":"2026-05-07T13:44:38.268202Z","end":"2026-05-07T13:44:39.805866Z","steps":["trace[410497235] 'process raft request' (duration: 1.537152008s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:39.80596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.194553972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T13:44:39.806047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.537708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T13:44:39.80607Z","caller":"traceutil/trace.go:171","msg":"trace[124498473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"2.194702327s","start":"2026-05-07T13:44:37.611343Z","end":"2026-05-07T13:44:39.806046Z","steps":["trace[124498473] 'agreement among raft nodes before linearized reading' (duration: 2.194514021s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:39.806087Z","caller":"traceutil/trace.go:171","msg":"trace[1399053026] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:416; }","duration":"360.593139ms","start":"2026-05-07T13:44:39.445481Z","end":"2026-05-07T13:44:39.806075Z","steps":["trace[1399053026] 'agreement among raft nodes before linearized reading' (duration: 360.543808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:39.806128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:39.445469Z","time spent":"360.651872ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.156\" "} {"level":"warn","ts":"2026-05-07T13:44:39.806138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:37.611326Z","time spent":"2.194799879s","remote":"127.0.0.1:56462","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-07T13:44:39.806154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:38.268175Z","time spent":"1.537845159s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:39.8062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.137361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ad4c71278aaef5\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-05-07T13:44:39.806277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.194140198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T13:44:39.805955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.570034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:39.806309Z","caller":"traceutil/trace.go:171","msg":"trace[510180662] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"2.194181369s","start":"2026-05-07T13:44:37.612118Z","end":"2026-05-07T13:44:39.806299Z","steps":["trace[510180662] 'agreement among raft nodes before linearized reading' (duration: 2.194128398s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:39.806326Z","caller":"traceutil/trace.go:171","msg":"trace[701546761] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18ad4c71278aaef5; range_end:; response_count:1; response_revision:416; }","duration":"382.321596ms","start":"2026-05-07T13:44:39.423973Z","end":"2026-05-07T13:44:39.806294Z","steps":["trace[701546761] 'agreement among raft nodes before linearized reading' (duration: 382.059228ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:39.806341Z","caller":"traceutil/trace.go:171","msg":"trace[1891675506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"381.983286ms","start":"2026-05-07T13:44:39.424345Z","end":"2026-05-07T13:44:39.806328Z","steps":["trace[1891675506] 'agreement among raft nodes before linearized reading' (duration: 381.579724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:39.806008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.683768424s","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-07T13:44:39.806376Z","caller":"traceutil/trace.go:171","msg":"trace[2079196161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"2.684171416s","start":"2026-05-07T13:44:37.122196Z","end":"2026-05-07T13:44:39.806368Z","steps":["trace[2079196161] 'agreement among raft nodes before linearized reading' (duration: 2.683783285s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:39.806383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:39.424329Z","time spent":"382.042888ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:44:39.806377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:39.423956Z","time spent":"382.410849ms","remote":"127.0.0.1:56284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ad4c71278aaef5\" "} {"level":"warn","ts":"2026-05-07T13:44:39.806414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:37.122176Z","time spent":"2.684229448s","remote":"127.0.0.1:56462","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-07T13:44:39.806337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:37.612113Z","time spent":"2.19421829s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T13:44:40.07755Z","caller":"traceutil/trace.go:171","msg":"trace[192858027] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"264.138018ms","start":"2026-05-07T13:44:39.813385Z","end":"2026-05-07T13:44:40.077524Z","steps":["trace[192858027] 'process raft request' (duration: 264.095977ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:40.077591Z","caller":"traceutil/trace.go:171","msg":"trace[1377160269] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"268.036977ms","start":"2026-05-07T13:44:39.809532Z","end":"2026-05-07T13:44:40.077569Z","steps":["trace[1377160269] 'process raft request' (duration: 267.676896ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:40.077853Z","caller":"traceutil/trace.go:171","msg":"trace[598222903] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"264.573403ms","start":"2026-05-07T13:44:39.813228Z","end":"2026-05-07T13:44:40.077801Z","steps":["trace[598222903] 'process raft request' (duration: 264.221632ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:40.078394Z","caller":"traceutil/trace.go:171","msg":"trace[1243804282] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"265.942644ms","start":"2026-05-07T13:44:39.811758Z","end":"2026-05-07T13:44:40.0777Z","steps":["trace[1243804282] 'process raft request' (duration: 265.635124ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:40.096339Z","caller":"traceutil/trace.go:171","msg":"trace[708640447] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"212.13789ms","start":"2026-05-07T13:44:39.884169Z","end":"2026-05-07T13:44:40.096307Z","steps":["trace[708640447] 'process raft request' (duration: 211.936203ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:42.190553Z","caller":"traceutil/trace.go:171","msg":"trace[1331886017] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"102.721884ms","start":"2026-05-07T13:44:42.087791Z","end":"2026-05-07T13:44:42.190513Z","steps":["trace[1331886017] 'process raft request' (duration: 102.60636ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:42.193549Z","caller":"traceutil/trace.go:171","msg":"trace[1238291739] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"105.39331ms","start":"2026-05-07T13:44:42.088142Z","end":"2026-05-07T13:44:42.193535Z","steps":["trace[1238291739] 'process raft request' (duration: 105.358689ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:42.193866Z","caller":"traceutil/trace.go:171","msg":"trace[432687912] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"105.830913ms","start":"2026-05-07T13:44:42.088024Z","end":"2026-05-07T13:44:42.193855Z","steps":["trace[432687912] 'process raft request' (duration: 105.411771ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:43.612125Z","caller":"traceutil/trace.go:171","msg":"trace[13878065] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"163.894968ms","start":"2026-05-07T13:44:43.448206Z","end":"2026-05-07T13:44:43.612101Z","steps":["trace[13878065] 'process raft request' (duration: 163.749083ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:44.361494Z","caller":"traceutil/trace.go:171","msg":"trace[1629305045] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"158.850696ms","start":"2026-05-07T13:44:44.202628Z","end":"2026-05-07T13:44:44.361479Z","steps":["trace[1629305045] 'process raft request' (duration: 158.800744ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:44.361492Z","caller":"traceutil/trace.go:171","msg":"trace[13388367] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:444; }","duration":"141.415654ms","start":"2026-05-07T13:44:44.220057Z","end":"2026-05-07T13:44:44.361472Z","steps":["trace[13388367] 'read index received' (duration: 114.267814ms)","trace[13388367] 'applied index is now lower than readState.Index' (duration: 27.1469ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:44.361612Z","caller":"traceutil/trace.go:171","msg":"trace[1918405988] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"161.078089ms","start":"2026-05-07T13:44:44.200483Z","end":"2026-05-07T13:44:44.361561Z","steps":["trace[1918405988] 'process raft request' (duration: 133.882867ms)","trace[1918405988] 'compare' (duration: 26.986196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:44.361775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.673972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4376"} {"level":"info","ts":"2026-05-07T13:44:44.361855Z","caller":"traceutil/trace.go:171","msg":"trace[1397910994] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:430; }","duration":"141.810325ms","start":"2026-05-07T13:44:44.220032Z","end":"2026-05-07T13:44:44.361843Z","steps":["trace[1397910994] 'agreement among raft nodes before linearized reading' (duration: 141.543897ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:45.435155Z","caller":"traceutil/trace.go:171","msg":"trace[1870821420] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"150.313585ms","start":"2026-05-07T13:44:45.284824Z","end":"2026-05-07T13:44:45.435138Z","steps":["trace[1870821420] 'process raft request' (duration: 150.223483ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:45.435197Z","caller":"traceutil/trace.go:171","msg":"trace[53796160] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"151.555361ms","start":"2026-05-07T13:44:45.28362Z","end":"2026-05-07T13:44:45.435175Z","steps":["trace[53796160] 'process raft request' (duration: 129.125553ms)","trace[53796160] 'compare' (duration: 21.923444ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:45.758469Z","caller":"traceutil/trace.go:171","msg":"trace[1511306043] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"112.955925ms","start":"2026-05-07T13:44:45.645477Z","end":"2026-05-07T13:44:45.758433Z","steps":["trace[1511306043] 'process raft request' (duration: 90.732852ms)","trace[1511306043] 'compare' (duration: 22.005627ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:45.758672Z","caller":"traceutil/trace.go:171","msg":"trace[2126302258] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"112.927823ms","start":"2026-05-07T13:44:45.64573Z","end":"2026-05-07T13:44:45.758658Z","steps":["trace[2126302258] 'process raft request' (duration: 112.716187ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:45.758537Z","caller":"traceutil/trace.go:171","msg":"trace[1246085250] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"112.873762ms","start":"2026-05-07T13:44:45.645637Z","end":"2026-05-07T13:44:45.758511Z","steps":["trace[1246085250] 'process raft request' (duration: 112.731378ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:45.758823Z","caller":"traceutil/trace.go:171","msg":"trace[965086878] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"104.357769ms","start":"2026-05-07T13:44:45.65444Z","end":"2026-05-07T13:44:45.758798Z","steps":["trace[965086878] 'process raft request' (duration: 104.170194ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:47.193985Z","caller":"traceutil/trace.go:171","msg":"trace[870608440] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"178.898979ms","start":"2026-05-07T13:44:47.015066Z","end":"2026-05-07T13:44:47.193965Z","steps":["trace[870608440] 'process raft request' (duration: 178.784806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:48.055319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.896378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:48.055402Z","caller":"traceutil/trace.go:171","msg":"trace[1470815924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"637.01961ms","start":"2026-05-07T13:44:47.418363Z","end":"2026-05-07T13:44:48.055382Z","steps":["trace[1470815924] 'range keys from in-memory index tree' (duration: 636.820715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:48.055429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.420371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-07T13:44:48.055442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:47.418346Z","time spent":"637.085733ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:44:48.055458Z","caller":"traceutil/trace.go:171","msg":"trace[283281214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"145.489003ms","start":"2026-05-07T13:44:47.90996Z","end":"2026-05-07T13:44:48.055449Z","steps":["trace[283281214] 'range keys from in-memory index tree' (duration: 145.316779ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:50.099736Z","caller":"traceutil/trace.go:171","msg":"trace[883199043] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"124.21895ms","start":"2026-05-07T13:44:49.975482Z","end":"2026-05-07T13:44:50.099701Z","steps":["trace[883199043] 'process raft request' (duration: 123.724196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:58.378046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.532816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:58.378129Z","caller":"traceutil/trace.go:171","msg":"trace[1555578848] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:522; }","duration":"107.664491ms","start":"2026-05-07T13:44:58.270447Z","end":"2026-05-07T13:44:58.378112Z","steps":["trace[1555578848] 'range keys from in-memory index tree' (duration: 107.464705ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:58.600158Z","caller":"traceutil/trace.go:171","msg":"trace[824605501] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"147.086739ms","start":"2026-05-07T13:44:58.453037Z","end":"2026-05-07T13:44:58.600124Z","steps":["trace[824605501] 'process raft request' (duration: 146.333121ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:58.67261Z","caller":"traceutil/trace.go:171","msg":"trace[2007281459] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"179.846912ms","start":"2026-05-07T13:44:58.49274Z","end":"2026-05-07T13:44:58.672587Z","steps":["trace[2007281459] 'read index received' (duration: 106.630676ms)","trace[2007281459] 'applied index is now lower than readState.Index' (duration: 73.215066ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:58.672786Z","caller":"traceutil/trace.go:171","msg":"trace[288221188] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"219.408294ms","start":"2026-05-07T13:44:58.453331Z","end":"2026-05-07T13:44:58.67274Z","steps":["trace[288221188] 'process raft request' (duration: 219.162858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:58.672871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.958625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:58.672947Z","caller":"traceutil/trace.go:171","msg":"trace[1330528195] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:525; }","duration":"180.203431ms","start":"2026-05-07T13:44:58.492699Z","end":"2026-05-07T13:44:58.672903Z","steps":["trace[1330528195] 'agreement among raft nodes before linearized reading' (duration: 179.970705ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.107806Z","caller":"traceutil/trace.go:171","msg":"trace[64425434] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"207.9181ms","start":"2026-05-07T13:44:58.899872Z","end":"2026-05-07T13:44:59.10779Z","steps":["trace[64425434] 'process raft request' (duration: 207.885519ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.107862Z","caller":"traceutil/trace.go:171","msg":"trace[1057990827] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"220.479516ms","start":"2026-05-07T13:44:58.887355Z","end":"2026-05-07T13:44:59.107834Z","steps":["trace[1057990827] 'process raft request' (duration: 220.291421ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.107893Z","caller":"traceutil/trace.go:171","msg":"trace[1482346115] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"228.611209ms","start":"2026-05-07T13:44:58.879248Z","end":"2026-05-07T13:44:59.107859Z","steps":["trace[1482346115] 'process raft request' (duration: 227.719698ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.10793Z","caller":"traceutil/trace.go:171","msg":"trace[1268682286] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"209.349217ms","start":"2026-05-07T13:44:58.898573Z","end":"2026-05-07T13:44:59.107922Z","steps":["trace[1268682286] 'process raft request' (duration: 209.160542ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.107962Z","caller":"traceutil/trace.go:171","msg":"trace[522308187] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"209.85855ms","start":"2026-05-07T13:44:58.898093Z","end":"2026-05-07T13:44:59.107952Z","steps":["trace[522308187] 'process raft request' (duration: 209.582073ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.107909Z","caller":"traceutil/trace.go:171","msg":"trace[468923975] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"209.639044ms","start":"2026-05-07T13:44:58.898246Z","end":"2026-05-07T13:44:59.107885Z","steps":["trace[468923975] 'process raft request' (duration: 209.45684ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.108062Z","caller":"traceutil/trace.go:171","msg":"trace[1110637674] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"227.299586ms","start":"2026-05-07T13:44:58.880746Z","end":"2026-05-07T13:44:59.108046Z","steps":["trace[1110637674] 'process raft request' (duration: 226.855465ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.372914Z","caller":"traceutil/trace.go:171","msg":"trace[1935793557] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"468.853944ms","start":"2026-05-07T13:44:58.903995Z","end":"2026-05-07T13:44:59.372849Z","steps":["trace[1935793557] 'process raft request' (duration: 467.976071ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.373375Z","caller":"traceutil/trace.go:171","msg":"trace[274811369] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"464.72319ms","start":"2026-05-07T13:44:58.908624Z","end":"2026-05-07T13:44:59.373347Z","steps":["trace[274811369] 'process raft request' (duration: 464.283489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.374055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:58.903985Z","time spent":"469.154302ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:44:59.374155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:58.908613Z","time spent":"465.450397ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:44:59.374362Z","caller":"traceutil/trace.go:171","msg":"trace[121122655] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"465.870528ms","start":"2026-05-07T13:44:58.908428Z","end":"2026-05-07T13:44:59.374298Z","steps":["trace[121122655] 'process raft request' (duration: 464.181237ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.374904Z","caller":"traceutil/trace.go:171","msg":"trace[533003444] transaction","detail":"{read_only:false; number_of_response:1; response_revision:537; }","duration":"464.199165ms","start":"2026-05-07T13:44:58.910663Z","end":"2026-05-07T13:44:59.374862Z","steps":["trace[533003444] 'process raft request' (duration: 462.295988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.37513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:58.908413Z","time spent":"466.049512ms","remote":"127.0.0.1:56716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:44:59.375289Z","caller":"traceutil/trace.go:171","msg":"trace[1279934742] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"453.516038ms","start":"2026-05-07T13:44:58.921664Z","end":"2026-05-07T13:44:59.37518Z","steps":["trace[1279934742] 'process raft request' (duration: 451.429227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.375492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:58.921632Z","time spent":"453.712182ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:44:59.375821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:58.91065Z","time spent":"464.346139ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:44:59.378863Z","caller":"traceutil/trace.go:171","msg":"trace[1279840524] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"366.164269ms","start":"2026-05-07T13:44:59.012662Z","end":"2026-05-07T13:44:59.378826Z","steps":["trace[1279840524] 'process raft request' (duration: 360.55828ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.379204Z","caller":"traceutil/trace.go:171","msg":"trace[1385335429] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"464.580056ms","start":"2026-05-07T13:44:58.908498Z","end":"2026-05-07T13:44:59.373078Z","steps":["trace[1385335429] 'process raft request' (duration: 464.3463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.379339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:58.908481Z","time spent":"470.77658ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:59.380465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.012643Z","time spent":"366.313303ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:44:59.469572Z","caller":"traceutil/trace.go:171","msg":"trace[1040793718] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"348.014858ms","start":"2026-05-07T13:44:59.121499Z","end":"2026-05-07T13:44:59.469514Z","steps":["trace[1040793718] 'process raft request' (duration: 347.921635ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.469565Z","caller":"traceutil/trace.go:171","msg":"trace[1559754410] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"354.736456ms","start":"2026-05-07T13:44:59.114758Z","end":"2026-05-07T13:44:59.469494Z","steps":["trace[1559754410] 'process raft request' (duration: 354.48255ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.469692Z","caller":"traceutil/trace.go:171","msg":"trace[637152831] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"349.865974ms","start":"2026-05-07T13:44:59.119785Z","end":"2026-05-07T13:44:59.469651Z","steps":["trace[637152831] 'process raft request' (duration: 349.587457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.469787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.121486Z","time spent":"348.166492ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:44:59.469797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.114733Z","time spent":"354.9409ms","remote":"127.0.0.1:56716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:44:59.469878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.119766Z","time spent":"350.021158ms","remote":"127.0.0.1:56716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:44:59.470227Z","caller":"traceutil/trace.go:171","msg":"trace[29580657] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"348.231504ms","start":"2026-05-07T13:44:59.121978Z","end":"2026-05-07T13:44:59.470209Z","steps":["trace[29580657] 'process raft request' (duration: 347.504846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.470277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.121967Z","time spent":"348.288755ms","remote":"127.0.0.1:56716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:44:59.470522Z","caller":"traceutil/trace.go:171","msg":"trace[191817954] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"348.51794ms","start":"2026-05-07T13:44:59.121996Z","end":"2026-05-07T13:44:59.470514Z","steps":["trace[191817954] 'process raft request' (duration: 348.09834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.470565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.121988Z","time spent":"348.558972ms","remote":"127.0.0.1:56716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:44:59.47086Z","caller":"traceutil/trace.go:171","msg":"trace[849957569] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"345.331092ms","start":"2026-05-07T13:44:59.125521Z","end":"2026-05-07T13:44:59.470852Z","steps":["trace[849957569] 'process raft request' (duration: 344.86891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.470933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.125512Z","time spent":"345.372633ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:44:59.47129Z","caller":"traceutil/trace.go:171","msg":"trace[883255823] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"344.142632ms","start":"2026-05-07T13:44:59.127079Z","end":"2026-05-07T13:44:59.471222Z","steps":["trace[883255823] 'process raft request' (duration: 343.946347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.471666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.127069Z","time spent":"344.428559ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:44:59.471314Z","caller":"traceutil/trace.go:171","msg":"trace[1097584202] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"344.530982ms","start":"2026-05-07T13:44:59.126761Z","end":"2026-05-07T13:44:59.471292Z","steps":["trace[1097584202] 'process raft request' (duration: 344.002779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.126753Z","time spent":"345.190148ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:44:59.691787Z","caller":"traceutil/trace.go:171","msg":"trace[2107747858] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"301.932182ms","start":"2026-05-07T13:44:59.389818Z","end":"2026-05-07T13:44:59.691751Z","steps":["trace[2107747858] 'process raft request' (duration: 291.257368ms)","trace[2107747858] 'compare' (duration: 10.466779ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:44:59.691939Z","caller":"traceutil/trace.go:171","msg":"trace[2131218277] transaction","detail":"{read_only:false; number_of_response:1; response_revision:548; }","duration":"293.671247ms","start":"2026-05-07T13:44:59.398163Z","end":"2026-05-07T13:44:59.691834Z","steps":["trace[2131218277] 'process raft request' (duration: 293.511663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.69195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:44:59.389797Z","time spent":"302.069135ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:44:59.692637Z","caller":"traceutil/trace.go:171","msg":"trace[1727794411] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"284.440079ms","start":"2026-05-07T13:44:59.40818Z","end":"2026-05-07T13:44:59.69262Z","steps":["trace[1727794411] 'process raft request' (duration: 283.587657ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.692658Z","caller":"traceutil/trace.go:171","msg":"trace[2101185549] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"244.728882ms","start":"2026-05-07T13:44:59.447912Z","end":"2026-05-07T13:44:59.692641Z","steps":["trace[2101185549] 'process raft request' (duration: 244.64913ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.692614Z","caller":"traceutil/trace.go:171","msg":"trace[1395396464] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:562; }","duration":"261.736804ms","start":"2026-05-07T13:44:59.430864Z","end":"2026-05-07T13:44:59.692601Z","steps":["trace[1395396464] 'read index received' (duration: 38.6084ms)","trace[1395396464] 'applied index is now lower than readState.Index' (duration: 223.127514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:44:59.692936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.069513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:44:59.692979Z","caller":"traceutil/trace.go:171","msg":"trace[932751246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"262.140784ms","start":"2026-05-07T13:44:59.430827Z","end":"2026-05-07T13:44:59.692968Z","steps":["trace[932751246] 'agreement among raft nodes before linearized reading' (duration: 262.064302ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.706467Z","caller":"traceutil/trace.go:171","msg":"trace[1882950493] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"189.441367ms","start":"2026-05-07T13:44:59.51701Z","end":"2026-05-07T13:44:59.706451Z","steps":["trace[1882950493] 'process raft request' (duration: 189.295584ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.706709Z","caller":"traceutil/trace.go:171","msg":"trace[302744478] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"216.060059ms","start":"2026-05-07T13:44:59.490641Z","end":"2026-05-07T13:44:59.706701Z","steps":["trace[302744478] 'process raft request' (duration: 215.31465ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.706759Z","caller":"traceutil/trace.go:171","msg":"trace[2106746517] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"213.610868ms","start":"2026-05-07T13:44:59.493134Z","end":"2026-05-07T13:44:59.706744Z","steps":["trace[2106746517] 'process raft request' (duration: 213.010753ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.706818Z","caller":"traceutil/trace.go:171","msg":"trace[846537891] transaction","detail":"{read_only:false; number_of_response:0; response_revision:550; }","duration":"231.38013ms","start":"2026-05-07T13:44:59.475433Z","end":"2026-05-07T13:44:59.706813Z","steps":["trace[846537891] 'process raft request' (duration: 230.371735ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:44:59.706863Z","caller":"traceutil/trace.go:171","msg":"trace[235608576] transaction","detail":"{read_only:false; number_of_response:1; response_revision:550; }","duration":"228.214421ms","start":"2026-05-07T13:44:59.47864Z","end":"2026-05-07T13:44:59.706854Z","steps":["trace[235608576] 'process raft request' (duration: 227.241217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:44:59.706963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.032841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T13:44:59.707027Z","caller":"traceutil/trace.go:171","msg":"trace[1572703642] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:553; }","duration":"260.130994ms","start":"2026-05-07T13:44:59.446883Z","end":"2026-05-07T13:44:59.707014Z","steps":["trace[1572703642] 'agreement among raft nodes before linearized reading' (duration: 260.026072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:00.700513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930834886,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:01.144141Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.006966942s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:45:01.147494Z","caller":"traceutil/trace.go:171","msg":"trace[1338420713] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.011981844s","start":"2026-05-07T13:45:00.135488Z","end":"2026-05-07T13:45:01.14747Z","steps":["trace[1338420713] 'process raft request' (duration: 1.008897799s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.147766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:00.135468Z","time spent":"1.012203989s","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:01.156336Z","caller":"traceutil/trace.go:171","msg":"trace[955346760] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"948.211452ms","start":"2026-05-07T13:45:00.208102Z","end":"2026-05-07T13:45:01.156313Z","steps":["trace[955346760] 'process raft request' (duration: 947.960336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.156543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:00.208081Z","time spent":"948.378975ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:01.156695Z","caller":"traceutil/trace.go:171","msg":"trace[1930557969] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:602; }","duration":"957.255434ms","start":"2026-05-07T13:45:00.199417Z","end":"2026-05-07T13:45:01.156672Z","steps":["trace[1930557969] 'read index received' (duration: 945.003396ms)","trace[1930557969] 'applied index is now lower than readState.Index' (duration: 12.251278ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:45:01.156778Z","caller":"traceutil/trace.go:171","msg":"trace[1226292689] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"820.590529ms","start":"2026-05-07T13:45:00.336167Z","end":"2026-05-07T13:45:01.156758Z","steps":["trace[1226292689] 'process raft request' (duration: 820.21763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.156821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.384697ms","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-07T13:45:01.156851Z","caller":"traceutil/trace.go:171","msg":"trace[1748315662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"957.431888ms","start":"2026-05-07T13:45:00.199411Z","end":"2026-05-07T13:45:01.156843Z","steps":["trace[1748315662] 'agreement among raft nodes before linearized reading' (duration: 957.342476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.156859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:00.336144Z","time spent":"820.681032ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:45:01.15688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:00.199102Z","time spent":"957.772066ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:45:01.157011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.358293ms","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-07T13:45:01.157092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.82801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:45:01.157135Z","caller":"traceutil/trace.go:171","msg":"trace[1380392568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"479.908622ms","start":"2026-05-07T13:45:00.677214Z","end":"2026-05-07T13:45:01.157122Z","steps":["trace[1380392568] 'agreement among raft nodes before linearized reading' (duration: 479.82634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.157145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.79291ms","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-07T13:45:01.157169Z","caller":"traceutil/trace.go:171","msg":"trace[567425508] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"553.839232ms","start":"2026-05-07T13:45:00.603323Z","end":"2026-05-07T13:45:01.157162Z","steps":["trace[567425508] 'agreement among raft nodes before linearized reading' (duration: 553.797281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.157197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:00.603311Z","time spent":"553.881813ms","remote":"127.0.0.1:56462","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-07T13:45:01.157168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:00.6772Z","time spent":"479.961143ms","remote":"127.0.0.1:56462","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-07T13:45:01.157067Z","caller":"traceutil/trace.go:171","msg":"trace[530519071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"703.444725ms","start":"2026-05-07T13:45:00.453609Z","end":"2026-05-07T13:45:01.157053Z","steps":["trace[530519071] 'agreement among raft nodes before linearized reading' (duration: 703.348992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.157637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:00.453593Z","time spent":"704.03126ms","remote":"127.0.0.1:56462","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-07T13:45:01.918568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930834911,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:01.990095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.491379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:45:01.991327Z","caller":"traceutil/trace.go:171","msg":"trace[944119925] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"735.479984ms","start":"2026-05-07T13:45:01.255006Z","end":"2026-05-07T13:45:01.990486Z","steps":["trace[944119925] 'process raft request' (duration: 16.485953ms)","trace[944119925] 'compare' (duration: 718.229803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:45:01.991697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:01.254986Z","time spent":"736.466188ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:01.992179Z","caller":"traceutil/trace.go:171","msg":"trace[1466450703] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"733.2654ms","start":"2026-05-07T13:45:01.258878Z","end":"2026-05-07T13:45:01.992144Z","steps":["trace[1466450703] 'process raft request' (duration: 731.353293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.992378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:01.258852Z","time spent":"733.407983ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:01.998841Z","caller":"traceutil/trace.go:171","msg":"trace[1063554583] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"581.017415ms","start":"2026-05-07T13:45:01.417776Z","end":"2026-05-07T13:45:01.998794Z","steps":["trace[1063554583] 'read index received' (duration: 572.291842ms)","trace[1063554583] 'applied index is now lower than readState.Index' (duration: 8.724443ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:45:01.998959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.171618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:45:01.998987Z","caller":"traceutil/trace.go:171","msg":"trace[1835698687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"581.27037ms","start":"2026-05-07T13:45:01.417709Z","end":"2026-05-07T13:45:01.99898Z","steps":["trace[1835698687] 'agreement among raft nodes before linearized reading' (duration: 581.190089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.999023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:01.417685Z","time spent":"581.333052ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T13:45:01.999289Z","caller":"traceutil/trace.go:171","msg":"trace[1043055255] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"711.515559ms","start":"2026-05-07T13:45:01.287763Z","end":"2026-05-07T13:45:01.999279Z","steps":["trace[1043055255] 'process raft request' (duration: 710.945995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:01.999365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:01.287727Z","time spent":"711.589671ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:45:02.850096Z","caller":"traceutil/trace.go:171","msg":"trace[1363874716] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"170.213625ms","start":"2026-05-07T13:45:02.679855Z","end":"2026-05-07T13:45:02.850069Z","steps":["trace[1363874716] 'read index received' (duration: 169.971129ms)","trace[1363874716] 'applied index is now lower than readState.Index' (duration: 241.346µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:45:02.85037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.487722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-05-07T13:45:02.850456Z","caller":"traceutil/trace.go:171","msg":"trace[1179532502] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:605; }","duration":"170.591654ms","start":"2026-05-07T13:45:02.679819Z","end":"2026-05-07T13:45:02.85041Z","steps":["trace[1179532502] 'agreement among raft nodes before linearized reading' (duration: 170.361619ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:02.850835Z","caller":"traceutil/trace.go:171","msg":"trace[1074756075] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"243.539498ms","start":"2026-05-07T13:45:02.607276Z","end":"2026-05-07T13:45:02.850816Z","steps":["trace[1074756075] 'process raft request' (duration: 242.439242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:08.702054Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.010259508s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:45:08.702438Z","caller":"traceutil/trace.go:171","msg":"trace[253301792] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"283.772174ms","start":"2026-05-07T13:45:08.418642Z","end":"2026-05-07T13:45:08.702414Z","steps":["trace[253301792] 'read index received' (duration: 283.545119ms)","trace[253301792] 'applied index is now lower than readState.Index' (duration: 226.255µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:45:08.702537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.874696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:45:08.702569Z","caller":"traceutil/trace.go:171","msg":"trace[1213347032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"283.943108ms","start":"2026-05-07T13:45:08.418615Z","end":"2026-05-07T13:45:08.702558Z","steps":["trace[1213347032] 'agreement among raft nodes before linearized reading' (duration: 283.859876ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:08.702611Z","caller":"traceutil/trace.go:171","msg":"trace[1850214263] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.010865422s","start":"2026-05-07T13:45:07.691692Z","end":"2026-05-07T13:45:08.702557Z","steps":["trace[1850214263] 'process raft request' (duration: 1.010540215s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:08.702823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:07.691667Z","time spent":"1.011051867s","remote":"127.0.0.1:56522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:45:09.69552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835041,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:45:09.7314Z","caller":"traceutil/trace.go:171","msg":"trace[1602268605] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"536.727435ms","start":"2026-05-07T13:45:09.194613Z","end":"2026-05-07T13:45:09.731341Z","steps":["trace[1602268605] 'read index received' (duration: 531.367063ms)","trace[1602268605] 'applied index is now lower than readState.Index' (duration: 5.359522ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:45:09.731492Z","caller":"traceutil/trace.go:171","msg":"trace[1132416877] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"626.407124ms","start":"2026-05-07T13:45:09.105037Z","end":"2026-05-07T13:45:09.731444Z","steps":["trace[1132416877] 'process raft request' (duration: 624.294226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:09.731579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.949051ms","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-07T13:45:09.731625Z","caller":"traceutil/trace.go:171","msg":"trace[1416441405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"537.047073ms","start":"2026-05-07T13:45:09.194563Z","end":"2026-05-07T13:45:09.731611Z","steps":["trace[1416441405] 'agreement among raft nodes before linearized reading' (duration: 536.95055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:09.73172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.194543Z","time spent":"537.166956ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:45:09.731722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.588393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T13:45:09.73175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.105016Z","time spent":"626.575057ms","remote":"127.0.0.1:56314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:09.731813Z","caller":"traceutil/trace.go:171","msg":"trace[226572398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"536.711026ms","start":"2026-05-07T13:45:09.195078Z","end":"2026-05-07T13:45:09.731789Z","steps":["trace[226572398] 'agreement among raft nodes before linearized reading' (duration: 536.496841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:09.731866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.195069Z","time spent":"536.789307ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:45:09.732011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.028228ms","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-07T13:45:09.732044Z","caller":"traceutil/trace.go:171","msg":"trace[1548596482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"517.076559ms","start":"2026-05-07T13:45:09.214958Z","end":"2026-05-07T13:45:09.732034Z","steps":["trace[1548596482] 'agreement among raft nodes before linearized reading' (duration: 517.018038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:09.732071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.214949Z","time spent":"517.116082ms","remote":"127.0.0.1:56462","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-07T13:45:09.732018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.547296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T13:45:09.732255Z","caller":"traceutil/trace.go:171","msg":"trace[1373225566] range","detail":"{range_begin:/registry/masterleases/199.204.45.156; range_end:; response_count:1; response_revision:631; }","duration":"284.837323ms","start":"2026-05-07T13:45:09.447391Z","end":"2026-05-07T13:45:09.732228Z","steps":["trace[1373225566] 'agreement among raft nodes before linearized reading' (duration: 284.424893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:09.732185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.331526ms","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-07T13:45:09.732406Z","caller":"traceutil/trace.go:171","msg":"trace[838316774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"517.587372ms","start":"2026-05-07T13:45:09.214806Z","end":"2026-05-07T13:45:09.732393Z","steps":["trace[838316774] 'agreement among raft nodes before linearized reading' (duration: 517.339327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:09.732452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.214785Z","time spent":"517.655293ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T13:45:09.897411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.87787ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-05-07T13:45:10.849943Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06739825s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:45:10.850386Z","caller":"traceutil/trace.go:171","msg":"trace[550616166] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.113417335s","start":"2026-05-07T13:45:09.736947Z","end":"2026-05-07T13:45:10.850364Z","steps":["trace[550616166] 'process raft request' (duration: 1.11317748s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:10.850577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.736932Z","time spent":"1.113509828s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:10.850841Z","caller":"traceutil/trace.go:171","msg":"trace[1831363605] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.111396819s","start":"2026-05-07T13:45:09.73941Z","end":"2026-05-07T13:45:10.850807Z","steps":["trace[1831363605] 'process raft request' (duration: 1.111216765s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:10.850857Z","caller":"traceutil/trace.go:171","msg":"trace[941776504] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.113579089s","start":"2026-05-07T13:45:09.737173Z","end":"2026-05-07T13:45:10.850752Z","steps":["trace[941776504] 'process raft request' (duration: 1.113143399s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:10.850987Z","caller":"traceutil/trace.go:171","msg":"trace[833010602] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.111563183s","start":"2026-05-07T13:45:09.739417Z","end":"2026-05-07T13:45:10.850981Z","steps":["trace[833010602] 'process raft request' (duration: 1.111332798s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:10.851018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.739402Z","time spent":"1.111529212s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:45:10.851037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.739411Z","time spent":"1.111597053s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:45:10.851083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.737166Z","time spent":"1.113829864s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:10.850947Z","caller":"traceutil/trace.go:171","msg":"trace[144867464] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.101148446s","start":"2026-05-07T13:45:09.749787Z","end":"2026-05-07T13:45:10.850935Z","steps":["trace[144867464] 'process raft request' (duration: 1.101102085s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:10.851179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.749764Z","time spent":"1.101395832s","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:10.873099Z","caller":"traceutil/trace.go:171","msg":"trace[559499961] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:657; }","duration":"456.30582ms","start":"2026-05-07T13:45:10.41675Z","end":"2026-05-07T13:45:10.873055Z","steps":["trace[559499961] 'read index received' (duration: 433.482796ms)","trace[559499961] 'applied index is now lower than readState.Index' (duration: 22.820714ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:45:10.873337Z","caller":"traceutil/trace.go:171","msg":"trace[662399442] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.087655785s","start":"2026-05-07T13:45:09.785475Z","end":"2026-05-07T13:45:10.873131Z","steps":["trace[662399442] 'process raft request' (duration: 1.087293657s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:10.873363Z","caller":"traceutil/trace.go:171","msg":"trace[1006956909] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"974.873213ms","start":"2026-05-07T13:45:09.898471Z","end":"2026-05-07T13:45:10.873344Z","steps":["trace[1006956909] 'process raft request' (duration: 974.420643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:10.873441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.676188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:45:10.873494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.785449Z","time spent":"1.087949211s","remote":"127.0.0.1:56538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:45:10.873542Z","caller":"traceutil/trace.go:171","msg":"trace[547617877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"456.828392ms","start":"2026-05-07T13:45:10.416697Z","end":"2026-05-07T13:45:10.873526Z","steps":["trace[547617877] 'agreement among raft nodes before linearized reading' (duration: 456.546635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:10.873648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:09.898447Z","time spent":"975.035236ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:45:10.873633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:10.416675Z","time spent":"456.943774ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:45:11.918549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:12.419359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:12.92014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:13.418101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000172532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T13:45:13.418411Z","caller":"traceutil/trace.go:171","msg":"trace[1031483906] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000545891s","start":"2026-05-07T13:45:11.417845Z","end":"2026-05-07T13:45:13.418391Z","steps":["trace[1031483906] 'agreement among raft nodes before linearized reading' (duration: 2.000211693s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:13.418572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:11.41782Z","time spent":"2.000735345s","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:45:13.421204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:13.922323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:14.07513Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.793693917s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:45:14.076296Z","caller":"traceutil/trace.go:171","msg":"trace[378714878] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.80145339s","start":"2026-05-07T13:45:11.274819Z","end":"2026-05-07T13:45:14.076272Z","steps":["trace[378714878] 'process raft request' (duration: 2.800833917s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:14.076441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:11.274778Z","time spent":"2.801587433s","remote":"127.0.0.1:56314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:14.10088Z","caller":"traceutil/trace.go:171","msg":"trace[240027066] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"2.6829557s","start":"2026-05-07T13:45:11.417905Z","end":"2026-05-07T13:45:14.10086Z","steps":["trace[240027066] 'read index received' (duration: 2.657499565s)","trace[240027066] 'applied index is now lower than readState.Index' (duration: 25.455415ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:45:14.101251Z","caller":"traceutil/trace.go:171","msg":"trace[167652931] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.75554096s","start":"2026-05-07T13:45:11.345701Z","end":"2026-05-07T13:45:14.101242Z","steps":["trace[167652931] 'process raft request' (duration: 2.755032679s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:14.101396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:11.345672Z","time spent":"2.755641571s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:45:14.101657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24634471s","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-07T13:45:14.101691Z","caller":"traceutil/trace.go:171","msg":"trace[691559224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.246423612s","start":"2026-05-07T13:45:12.855259Z","end":"2026-05-07T13:45:14.101683Z","steps":["trace[691559224] 'agreement among raft nodes before linearized reading' (duration: 1.246282139s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:14.101712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:12.855237Z","time spent":"1.246470334s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T13:45:14.101777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.245719916s","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-07T13:45:14.101829Z","caller":"traceutil/trace.go:171","msg":"trace[70033344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.245783237s","start":"2026-05-07T13:45:12.856038Z","end":"2026-05-07T13:45:14.101821Z","steps":["trace[70033344] 'agreement among raft nodes before linearized reading' (duration: 1.245606013s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:14.101862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246040343s","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-07T13:45:14.101886Z","caller":"traceutil/trace.go:171","msg":"trace[672063571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"1.246073333s","start":"2026-05-07T13:45:12.855806Z","end":"2026-05-07T13:45:14.10188Z","steps":["trace[672063571] 'agreement among raft nodes before linearized reading' (duration: 1.246011102s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:14.101907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:12.855802Z","time spent":"1.246098085s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T13:45:14.101871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:12.856032Z","time spent":"1.245832769s","remote":"127.0.0.1:56462","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-07T13:45:14.101982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.719184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:45:14.102006Z","caller":"traceutil/trace.go:171","msg":"trace[2091533995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"682.781846ms","start":"2026-05-07T13:45:13.419219Z","end":"2026-05-07T13:45:14.102Z","steps":["trace[2091533995] 'agreement among raft nodes before linearized reading' (duration: 682.745655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:14.102032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:13.419201Z","time spent":"682.825127ms","remote":"127.0.0.1:56208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:45:14.105325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249573521s","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-07T13:45:14.105371Z","caller":"traceutil/trace.go:171","msg":"trace[839233867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.249652173s","start":"2026-05-07T13:45:12.855707Z","end":"2026-05-07T13:45:14.10536Z","steps":["trace[839233867] 'agreement among raft nodes before linearized reading' (duration: 1.246207517s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:14.105519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:12.855691Z","time spent":"1.249779625s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T13:45:14.648598Z","caller":"traceutil/trace.go:171","msg":"trace[1984456167] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"106.06722ms","start":"2026-05-07T13:45:14.542505Z","end":"2026-05-07T13:45:14.648572Z","steps":["trace[1984456167] 'process raft request' (duration: 105.525177ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:16.375063Z","caller":"traceutil/trace.go:171","msg":"trace[1508660747] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"301.911648ms","start":"2026-05-07T13:45:16.073123Z","end":"2026-05-07T13:45:16.375035Z","steps":["trace[1508660747] 'process raft request' (duration: 301.786695ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:16.375099Z","caller":"traceutil/trace.go:171","msg":"trace[54759950] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"101.196748ms","start":"2026-05-07T13:45:16.273884Z","end":"2026-05-07T13:45:16.37508Z","steps":["trace[54759950] 'process raft request' (duration: 101.062635ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:16.375123Z","caller":"traceutil/trace.go:171","msg":"trace[47322875] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"582.762431ms","start":"2026-05-07T13:45:15.792323Z","end":"2026-05-07T13:45:16.375086Z","steps":["trace[47322875] 'process raft request' (duration: 572.695385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:16.37521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:16.073092Z","time spent":"302.05306ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:45:16.375277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:15.792309Z","time spent":"582.900754ms","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T13:45:16.375547Z","caller":"traceutil/trace.go:171","msg":"trace[229617717] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"502.372354ms","start":"2026-05-07T13:45:15.873159Z","end":"2026-05-07T13:45:16.375531Z","steps":["trace[229617717] 'process raft request' (duration: 501.68438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:16.375639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:15.873132Z","time spent":"502.467796ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T13:45:16.375996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.741756ms","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-07T13:45:16.376067Z","caller":"traceutil/trace.go:171","msg":"trace[1471791086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"260.857568ms","start":"2026-05-07T13:45:16.11519Z","end":"2026-05-07T13:45:16.376048Z","steps":["trace[1471791086] 'agreement among raft nodes before linearized reading' (duration: 260.689275ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:16.376438Z","caller":"traceutil/trace.go:171","msg":"trace[1228481404] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:698; }","duration":"259.727614ms","start":"2026-05-07T13:45:16.115245Z","end":"2026-05-07T13:45:16.374972Z","steps":["trace[1228481404] 'read index received' (duration: 249.855882ms)","trace[1228481404] 'applied index is now lower than readState.Index' (duration: 9.870962ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:45:16.376664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.055901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:45:16.376732Z","caller":"traceutil/trace.go:171","msg":"trace[2043932675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"253.152842ms","start":"2026-05-07T13:45:16.123567Z","end":"2026-05-07T13:45:16.37672Z","steps":["trace[2043932675] 'agreement among raft nodes before linearized reading' (duration: 252.975279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:16.377237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.203915ms","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-07T13:45:16.377294Z","caller":"traceutil/trace.go:171","msg":"trace[330780522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:675; }","duration":"253.339147ms","start":"2026-05-07T13:45:16.123942Z","end":"2026-05-07T13:45:16.377281Z","steps":["trace[330780522] 'agreement among raft nodes before linearized reading' (duration: 253.214364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:16.377822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.875068ms","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-07T13:45:16.37787Z","caller":"traceutil/trace.go:171","msg":"trace[2041693559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"253.999231ms","start":"2026-05-07T13:45:16.12386Z","end":"2026-05-07T13:45:16.377859Z","steps":["trace[2041693559] 'agreement among raft nodes before linearized reading' (duration: 253.808327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:19.161452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.440824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-07T13:45:19.161564Z","caller":"traceutil/trace.go:171","msg":"trace[15944359] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:726; }","duration":"290.601838ms","start":"2026-05-07T13:45:18.870933Z","end":"2026-05-07T13:45:19.161535Z","steps":["trace[15944359] 'range keys from in-memory index tree' (duration: 290.25637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:30.29809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:30.798921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:31.299103Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:31.799324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:32.300332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:32.419707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000039419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T13:45:32.419809Z","caller":"traceutil/trace.go:171","msg":"trace[920849864] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000191402s","start":"2026-05-07T13:45:30.419596Z","end":"2026-05-07T13:45:32.419787Z","steps":["trace[920849864] 'agreement among raft nodes before linearized reading' (duration: 2.00007054s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:32.41986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:30.419575Z","time spent":"2.000274323s","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:45:32.80052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:33.301602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930835475,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T13:45:33.682601Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.739236319s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T13:45:33.682875Z","caller":"traceutil/trace.go:171","msg":"trace[691236401] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:844; }","duration":"3.886302034s","start":"2026-05-07T13:45:29.796559Z","end":"2026-05-07T13:45:33.682861Z","steps":["trace[691236401] 'read index received' (duration: 3.886135491s)","trace[691236401] 'applied index is now lower than readState.Index' (duration: 165.823µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:45:33.682955Z","caller":"traceutil/trace.go:171","msg":"trace[1656034336] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"3.931680642s","start":"2026-05-07T13:45:29.751265Z","end":"2026-05-07T13:45:33.682946Z","steps":["trace[1656034336] 'process raft request' (duration: 3.931475768s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.683045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:29.751253Z","time spent":"3.931729043s","remote":"127.0.0.1:56386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:45:33.683183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.518134033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:45:33.683224Z","caller":"traceutil/trace.go:171","msg":"trace[66478935] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:812; }","duration":"2.518219365s","start":"2026-05-07T13:45:31.164996Z","end":"2026-05-07T13:45:33.683215Z","steps":["trace[66478935] 'agreement among raft nodes before linearized reading' (duration: 2.518151604s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.683265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:31.164972Z","time spent":"2.518273016s","remote":"127.0.0.1:56478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:45:33.683467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735509946s","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-07T13:45:33.68343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.211284897s","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-07T13:45:33.683477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88565083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:45:33.683552Z","caller":"traceutil/trace.go:171","msg":"trace[63039616] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:812; }","duration":"1.885761022s","start":"2026-05-07T13:45:31.797781Z","end":"2026-05-07T13:45:33.683542Z","steps":["trace[63039616] 'agreement among raft nodes before linearized reading' (duration: 1.88565844s)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:33.683568Z","caller":"traceutil/trace.go:171","msg":"trace[363793435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"3.21146286s","start":"2026-05-07T13:45:30.472074Z","end":"2026-05-07T13:45:33.683537Z","steps":["trace[363793435] 'agreement among raft nodes before linearized reading' (duration: 3.211047172s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.683623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:31.797761Z","time spent":"1.885849953s","remote":"127.0.0.1:56626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:45:33.68368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.88711368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-07T13:45:33.68373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:30.472001Z","time spent":"3.211710404s","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T13:45:33.683511Z","caller":"traceutil/trace.go:171","msg":"trace[939361950] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:812; }","duration":"1.735585747s","start":"2026-05-07T13:45:31.947916Z","end":"2026-05-07T13:45:33.683502Z","steps":["trace[939361950] 'agreement among raft nodes before linearized reading' (duration: 1.735500636s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.6838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.205332713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T13:45:33.68382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:31.947897Z","time spent":"1.735907304s","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:45:33.683845Z","caller":"traceutil/trace.go:171","msg":"trace[1598068883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"3.205429935s","start":"2026-05-07T13:45:30.478403Z","end":"2026-05-07T13:45:33.683833Z","steps":["trace[1598068883] 'agreement among raft nodes before linearized reading' (duration: 3.205307421s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.683442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263108005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:45:33.683879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:30.478374Z","time spent":"3.205496976s","remote":"127.0.0.1:56462","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-07T13:45:33.683901Z","caller":"traceutil/trace.go:171","msg":"trace[1365797450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"1.263629865s","start":"2026-05-07T13:45:32.420261Z","end":"2026-05-07T13:45:33.683891Z","steps":["trace[1365797450] 'agreement among raft nodes before linearized reading' (duration: 1.263054303s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.683967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:32.420242Z","time spent":"1.263689255s","remote":"127.0.0.1:56208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:45:33.683978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.816276898s","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":"info","ts":"2026-05-07T13:45:33.684006Z","caller":"traceutil/trace.go:171","msg":"trace[1495203880] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:812; }","duration":"2.81633175s","start":"2026-05-07T13:45:30.867664Z","end":"2026-05-07T13:45:33.683995Z","steps":["trace[1495203880] 'agreement among raft nodes before linearized reading' (duration: 2.816278229s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.68404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:30.867641Z","time spent":"2.81639084s","remote":"127.0.0.1:56502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-07T13:45:33.683755Z","caller":"traceutil/trace.go:171","msg":"trace[358364581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"3.887196712s","start":"2026-05-07T13:45:29.796535Z","end":"2026-05-07T13:45:33.683732Z","steps":["trace[358364581] 'agreement among raft nodes before linearized reading' (duration: 3.886961676s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.684307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:29.796524Z","time spent":"3.887762032s","remote":"127.0.0.1:56462","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-07T13:45:33.684513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.211689315s","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-07T13:45:33.684552Z","caller":"traceutil/trace.go:171","msg":"trace[340561917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"3.211760296s","start":"2026-05-07T13:45:30.472782Z","end":"2026-05-07T13:45:33.684543Z","steps":["trace[340561917] 'agreement among raft nodes before linearized reading' (duration: 3.211665404s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.684578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:30.472761Z","time spent":"3.211809947s","remote":"127.0.0.1:56462","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-07T13:45:33.697481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.21910715s","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-07T13:45:33.697522Z","caller":"traceutil/trace.go:171","msg":"trace[1637832985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"3.219169692s","start":"2026-05-07T13:45:30.478344Z","end":"2026-05-07T13:45:33.697514Z","steps":["trace[1637832985] 'agreement among raft nodes before linearized reading' (duration: 3.205879164s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.697541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:30.478329Z","time spent":"3.219207972s","remote":"127.0.0.1:56462","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":"info","ts":"2026-05-07T13:45:33.827964Z","caller":"traceutil/trace.go:171","msg":"trace[982304643] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"139.090965ms","start":"2026-05-07T13:45:33.688849Z","end":"2026-05-07T13:45:33.82794Z","steps":["trace[982304643] 'process raft request' (duration: 138.950862ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:33.828341Z","caller":"traceutil/trace.go:171","msg":"trace[1761918128] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:846; }","duration":"139.187857ms","start":"2026-05-07T13:45:33.689138Z","end":"2026-05-07T13:45:33.828325Z","steps":["trace[1761918128] 'read index received' (duration: 139.183227ms)","trace[1761918128] 'applied index is now lower than readState.Index' (duration: 3.77µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:45:33.828497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.33736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18ad4c71278aaef5\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-05-07T13:45:33.828581Z","caller":"traceutil/trace.go:171","msg":"trace[868067991] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18ad4c71278aaef5; range_end:; response_count:1; response_revision:813; }","duration":"139.452401ms","start":"2026-05-07T13:45:33.689111Z","end":"2026-05-07T13:45:33.828564Z","steps":["trace[868067991] 'agreement among raft nodes before linearized reading' (duration: 139.305779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:33.829179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.978443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:45:33.829244Z","caller":"traceutil/trace.go:171","msg":"trace[1476810246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:817; }","duration":"131.058294ms","start":"2026-05-07T13:45:33.698172Z","end":"2026-05-07T13:45:33.82923Z","steps":["trace[1476810246] 'agreement among raft nodes before linearized reading' (duration: 130.945572ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:33.829436Z","caller":"traceutil/trace.go:171","msg":"trace[2039184100] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"129.428364ms","start":"2026-05-07T13:45:33.699997Z","end":"2026-05-07T13:45:33.829426Z","steps":["trace[2039184100] 'process raft request' (duration: 129.055076ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:33.829461Z","caller":"traceutil/trace.go:171","msg":"trace[1266898087] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"129.493715ms","start":"2026-05-07T13:45:33.699937Z","end":"2026-05-07T13:45:33.82943Z","steps":["trace[1266898087] 'process raft request' (duration: 129.082977ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:33.829528Z","caller":"traceutil/trace.go:171","msg":"trace[1300457819] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"129.487565ms","start":"2026-05-07T13:45:33.700032Z","end":"2026-05-07T13:45:33.829519Z","steps":["trace[1300457819] 'process raft request' (duration: 129.049506ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:33.82978Z","caller":"traceutil/trace.go:171","msg":"trace[315915904] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"131.416892ms","start":"2026-05-07T13:45:33.698351Z","end":"2026-05-07T13:45:33.829768Z","steps":["trace[315915904] 'process raft request' (duration: 130.605106ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:48.284738Z","caller":"traceutil/trace.go:171","msg":"trace[1284182043] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"365.504193ms","start":"2026-05-07T13:45:47.919209Z","end":"2026-05-07T13:45:48.284713Z","steps":["trace[1284182043] 'process raft request' (duration: 365.358841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:48.284894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:47.919186Z","time spent":"365.631615ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:48.365985Z","caller":"traceutil/trace.go:171","msg":"trace[107287229] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"445.92288ms","start":"2026-05-07T13:45:47.920044Z","end":"2026-05-07T13:45:48.365967Z","steps":["trace[107287229] 'process raft request' (duration: 445.633796ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:45:48.366269Z","caller":"traceutil/trace.go:171","msg":"trace[1424922749] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"446.083013ms","start":"2026-05-07T13:45:47.920166Z","end":"2026-05-07T13:45:48.366249Z","steps":["trace[1424922749] 'process raft request' (duration: 445.762468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:48.36641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:47.920156Z","time spent":"446.180725ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:45:48.366788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:47.920033Z","time spent":"446.245936ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:48.366989Z","caller":"traceutil/trace.go:171","msg":"trace[2096678278] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1011; }","duration":"143.57052ms","start":"2026-05-07T13:45:48.223387Z","end":"2026-05-07T13:45:48.366958Z","steps":["trace[2096678278] 'read index received' (duration: 61.21574ms)","trace[2096678278] 'applied index is now lower than readState.Index' (duration: 82.35357ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:45:48.367001Z","caller":"traceutil/trace.go:171","msg":"trace[1474160766] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"446.662071ms","start":"2026-05-07T13:45:47.920315Z","end":"2026-05-07T13:45:48.366977Z","steps":["trace[1474160766] 'process raft request' (duration: 445.854858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:48.368944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:47.920308Z","time spent":"448.556835ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:45:48.367044Z","caller":"traceutil/trace.go:171","msg":"trace[1058695033] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"444.531426ms","start":"2026-05-07T13:45:47.922443Z","end":"2026-05-07T13:45:48.366975Z","steps":["trace[1058695033] 'process raft request' (duration: 444.397414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:48.367213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.795144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kbdrb\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-05-07T13:45:48.369438Z","caller":"traceutil/trace.go:171","msg":"trace[270811502] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-kbdrb; range_end:; response_count:1; response_revision:980; }","duration":"146.066423ms","start":"2026-05-07T13:45:48.223347Z","end":"2026-05-07T13:45:48.369414Z","steps":["trace[270811502] 'agreement among raft nodes before linearized reading' (duration: 143.705412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:45:48.36949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:45:47.922435Z","time spent":"446.990748ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:46:02.639485Z","caller":"traceutil/trace.go:171","msg":"trace[2116228901] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"196.64678ms","start":"2026-05-07T13:46:02.442806Z","end":"2026-05-07T13:46:02.639453Z","steps":["trace[2116228901] 'process raft request' (duration: 196.476678ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:02.725348Z","caller":"traceutil/trace.go:171","msg":"trace[1180786246] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1073; }","duration":"280.079288ms","start":"2026-05-07T13:46:02.445245Z","end":"2026-05-07T13:46:02.725324Z","steps":["trace[1180786246] 'read index received' (duration: 194.056811ms)","trace[1180786246] 'applied index is now lower than readState.Index' (duration: 86.021187ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:46:02.725374Z","caller":"traceutil/trace.go:171","msg":"trace[177645920] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"281.036612ms","start":"2026-05-07T13:46:02.444308Z","end":"2026-05-07T13:46:02.725345Z","steps":["trace[177645920] 'process raft request' (duration: 252.465445ms)","trace[177645920] 'compare' (duration: 28.421845ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:46:02.725555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.860779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T13:46:02.725578Z","caller":"traceutil/trace.go:171","msg":"trace[2048075620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1035; }","duration":"278.90897ms","start":"2026-05-07T13:46:02.44666Z","end":"2026-05-07T13:46:02.725569Z","steps":["trace[2048075620] 'agreement among raft nodes before linearized reading' (duration: 278.779948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:46:02.725614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.467051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-07T13:46:02.7256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.330573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:46:02.725668Z","caller":"traceutil/trace.go:171","msg":"trace[199264543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"276.532302ms","start":"2026-05-07T13:46:02.449122Z","end":"2026-05-07T13:46:02.725654Z","steps":["trace[199264543] 'agreement among raft nodes before linearized reading' (duration: 276.361029ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:02.725686Z","caller":"traceutil/trace.go:171","msg":"trace[1232340154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"280.460455ms","start":"2026-05-07T13:46:02.445203Z","end":"2026-05-07T13:46:02.725664Z","steps":["trace[1232340154] 'agreement among raft nodes before linearized reading' (duration: 280.229262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:46:20.993917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.501294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:46:20.994533Z","caller":"traceutil/trace.go:171","msg":"trace[1760471274] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"156.422842ms","start":"2026-05-07T13:46:20.838092Z","end":"2026-05-07T13:46:20.994514Z","steps":["trace[1760471274] 'process raft request' (duration: 15.044866ms)","trace[1760471274] 'compare' (duration: 138.121219ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:46:21.098878Z","caller":"traceutil/trace.go:171","msg":"trace[2138988177] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"260.73414ms","start":"2026-05-07T13:46:20.838122Z","end":"2026-05-07T13:46:21.098856Z","steps":["trace[2138988177] 'process raft request' (duration: 258.838353ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:21.099029Z","caller":"traceutil/trace.go:171","msg":"trace[1298032421] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"260.879402ms","start":"2026-05-07T13:46:20.838127Z","end":"2026-05-07T13:46:21.099007Z","steps":["trace[1298032421] 'process raft request' (duration: 260.690549ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:29.779753Z","caller":"traceutil/trace.go:171","msg":"trace[1853615298] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"279.860509ms","start":"2026-05-07T13:46:29.499823Z","end":"2026-05-07T13:46:29.779683Z","steps":["trace[1853615298] 'process raft request' (duration: 277.720319ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:29.785878Z","caller":"traceutil/trace.go:171","msg":"trace[409077439] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"275.862544ms","start":"2026-05-07T13:46:29.509996Z","end":"2026-05-07T13:46:29.785858Z","steps":["trace[409077439] 'process raft request' (duration: 275.721082ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:33.098177Z","caller":"traceutil/trace.go:171","msg":"trace[2076410061] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"128.865511ms","start":"2026-05-07T13:46:32.969271Z","end":"2026-05-07T13:46:33.098137Z","steps":["trace[2076410061] 'process raft request' (duration: 122.20386ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:51.388087Z","caller":"traceutil/trace.go:171","msg":"trace[1710728498] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"113.037653ms","start":"2026-05-07T13:46:51.275022Z","end":"2026-05-07T13:46:51.388059Z","steps":["trace[1710728498] 'read index received' (duration: 112.246622ms)","trace[1710728498] 'applied index is now lower than readState.Index' (duration: 789.021µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:46:51.388396Z","caller":"traceutil/trace.go:171","msg":"trace[85472715] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"129.211668ms","start":"2026-05-07T13:46:51.259169Z","end":"2026-05-07T13:46:51.388381Z","steps":["trace[85472715] 'process raft request' (duration: 128.159454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:46:51.38881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.774032ms","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-07T13:46:51.388897Z","caller":"traceutil/trace.go:171","msg":"trace[1933388610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"113.893423ms","start":"2026-05-07T13:46:51.274987Z","end":"2026-05-07T13:46:51.38888Z","steps":["trace[1933388610] 'agreement among raft nodes before linearized reading' (duration: 113.686281ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:53.282722Z","caller":"traceutil/trace.go:171","msg":"trace[1960360874] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"118.019596ms","start":"2026-05-07T13:46:53.164663Z","end":"2026-05-07T13:46:53.282682Z","steps":["trace[1960360874] 'process raft request' (duration: 117.785723ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:46:53.405927Z","caller":"traceutil/trace.go:171","msg":"trace[843293944] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"119.685328ms","start":"2026-05-07T13:46:53.286211Z","end":"2026-05-07T13:46:53.405896Z","steps":["trace[843293944] 'process raft request' (duration: 51.110288ms)","trace[843293944] 'compare' (duration: 68.457709ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:46:53.64179Z","caller":"traceutil/trace.go:171","msg":"trace[1402564804] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1255; }","duration":"242.311522ms","start":"2026-05-07T13:46:53.399456Z","end":"2026-05-07T13:46:53.641768Z","steps":["trace[1402564804] 'read index received' (duration: 240.973235ms)","trace[1402564804] 'applied index is now lower than readState.Index' (duration: 1.337117ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:46:53.642055Z","caller":"traceutil/trace.go:171","msg":"trace[273922782] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"247.023072ms","start":"2026-05-07T13:46:53.39502Z","end":"2026-05-07T13:46:53.642043Z","steps":["trace[273922782] 'process raft request' (duration: 245.464233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:46:53.64237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.890819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:46:53.642408Z","caller":"traceutil/trace.go:171","msg":"trace[56469934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1205; }","duration":"242.986151ms","start":"2026-05-07T13:46:53.39941Z","end":"2026-05-07T13:46:53.642396Z","steps":["trace[56469934] 'agreement among raft nodes before linearized reading' (duration: 242.844139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:46:53.642606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.103569ms","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-07T13:46:53.642638Z","caller":"traceutil/trace.go:171","msg":"trace[558003801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1205; }","duration":"242.151719ms","start":"2026-05-07T13:46:53.400475Z","end":"2026-05-07T13:46:53.642626Z","steps":["trace[558003801] 'agreement among raft nodes before linearized reading' (duration: 242.071108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:46:53.642959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.887987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T13:46:53.642998Z","caller":"traceutil/trace.go:171","msg":"trace[321260902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"241.971079ms","start":"2026-05-07T13:46:53.401018Z","end":"2026-05-07T13:46:53.642989Z","steps":["trace[321260902] 'agreement among raft nodes before linearized reading' (duration: 241.793477ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:15.658384Z","caller":"traceutil/trace.go:171","msg":"trace[114870430] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"102.239994ms","start":"2026-05-07T13:47:15.556113Z","end":"2026-05-07T13:47:15.658353Z","steps":["trace[114870430] 'process raft request' (duration: 101.843219ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:15.982678Z","caller":"traceutil/trace.go:171","msg":"trace[1681426243] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"121.540734ms","start":"2026-05-07T13:47:15.861112Z","end":"2026-05-07T13:47:15.982653Z","steps":["trace[1681426243] 'process raft request' (duration: 121.494974ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:15.982717Z","caller":"traceutil/trace.go:171","msg":"trace[1750280217] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"121.622075ms","start":"2026-05-07T13:47:15.861071Z","end":"2026-05-07T13:47:15.982694Z","steps":["trace[1750280217] 'process raft request' (duration: 98.219845ms)","trace[1750280217] 'compare' (duration: 23.210827ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:47:17.540971Z","caller":"traceutil/trace.go:171","msg":"trace[1309671012] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"121.274905ms","start":"2026-05-07T13:47:17.41967Z","end":"2026-05-07T13:47:17.540945Z","steps":["trace[1309671012] 'read index received' (duration: 121.118123ms)","trace[1309671012] 'applied index is now lower than readState.Index' (duration: 155.842µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:47:17.541049Z","caller":"traceutil/trace.go:171","msg":"trace[105876509] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"121.61569ms","start":"2026-05-07T13:47:17.419402Z","end":"2026-05-07T13:47:17.541018Z","steps":["trace[105876509] 'process raft request' (duration: 121.393916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:17.541297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.455878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:47:17.541414Z","caller":"traceutil/trace.go:171","msg":"trace[1598627095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"121.749781ms","start":"2026-05-07T13:47:17.419584Z","end":"2026-05-07T13:47:17.541334Z","steps":["trace[1598627095] 'agreement among raft nodes before linearized reading' (duration: 121.473268ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:17.845139Z","caller":"traceutil/trace.go:171","msg":"trace[901575033] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"169.938785ms","start":"2026-05-07T13:47:17.675166Z","end":"2026-05-07T13:47:17.845105Z","steps":["trace[901575033] 'read index received' (duration: 169.649422ms)","trace[901575033] 'applied index is now lower than readState.Index' (duration: 287.403µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:47:17.84519Z","caller":"traceutil/trace.go:171","msg":"trace[161262973] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"178.128993ms","start":"2026-05-07T13:47:17.667007Z","end":"2026-05-07T13:47:17.845136Z","steps":["trace[161262973] 'process raft request' (duration: 177.847969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:17.845352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.159608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:47:17.845581Z","caller":"traceutil/trace.go:171","msg":"trace[1072301017] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1316; }","duration":"170.423842ms","start":"2026-05-07T13:47:17.675138Z","end":"2026-05-07T13:47:17.845562Z","steps":["trace[1072301017] 'agreement among raft nodes before linearized reading' (duration: 170.139148ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:17.895153Z","caller":"traceutil/trace.go:171","msg":"trace[887330825] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"168.999344ms","start":"2026-05-07T13:47:17.726123Z","end":"2026-05-07T13:47:17.895122Z","steps":["trace[887330825] 'process raft request' (duration: 168.799651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:17.895343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.890051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:95810"} {"level":"warn","ts":"2026-05-07T13:47:17.895395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.875256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-07T13:47:17.895409Z","caller":"traceutil/trace.go:171","msg":"trace[456644431] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1317; }","duration":"141.990613ms","start":"2026-05-07T13:47:17.753403Z","end":"2026-05-07T13:47:17.895393Z","steps":["trace[456644431] 'agreement among raft nodes before linearized reading' (duration: 141.73758ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:17.895436Z","caller":"traceutil/trace.go:171","msg":"trace[614536139] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1317; }","duration":"133.959636ms","start":"2026-05-07T13:47:17.761466Z","end":"2026-05-07T13:47:17.895426Z","steps":["trace[614536139] 'agreement among raft nodes before linearized reading' (duration: 133.868675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:18.103359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.639107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T13:47:18.103456Z","caller":"traceutil/trace.go:171","msg":"trace[1585744521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"116.803229ms","start":"2026-05-07T13:47:17.986631Z","end":"2026-05-07T13:47:18.103434Z","steps":["trace[1585744521] 'range keys from in-memory index tree' (duration: 116.404324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:18.103575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.682414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T13:47:18.103678Z","caller":"traceutil/trace.go:171","msg":"trace[1595647922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"114.850005ms","start":"2026-05-07T13:47:17.988806Z","end":"2026-05-07T13:47:18.103656Z","steps":["trace[1595647922] 'range keys from in-memory index tree' (duration: 114.479512ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:19.689969Z","caller":"traceutil/trace.go:171","msg":"trace[936678614] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"144.471774ms","start":"2026-05-07T13:47:19.545459Z","end":"2026-05-07T13:47:19.689931Z","steps":["trace[936678614] 'read index received' (duration: 144.315422ms)","trace[936678614] 'applied index is now lower than readState.Index' (duration: 154.092µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:47:19.690279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.804828ms","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-07T13:47:19.690351Z","caller":"traceutil/trace.go:171","msg":"trace[484491552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"144.92589ms","start":"2026-05-07T13:47:19.545407Z","end":"2026-05-07T13:47:19.690333Z","steps":["trace[484491552] 'agreement among raft nodes before linearized reading' (duration: 144.638557ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:20.014799Z","caller":"traceutil/trace.go:171","msg":"trace[100151385] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"323.540159ms","start":"2026-05-07T13:47:19.691229Z","end":"2026-05-07T13:47:20.014769Z","steps":["trace[100151385] 'process raft request' (duration: 231.807421ms)","trace[100151385] 'compare' (duration: 91.559816ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:47:20.014951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:19.691196Z","time spent":"323.67884ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:47:20.02958Z","caller":"traceutil/trace.go:171","msg":"trace[1322310492] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"336.206079ms","start":"2026-05-07T13:47:19.69335Z","end":"2026-05-07T13:47:20.029557Z","steps":["trace[1322310492] 'process raft request' (duration: 335.978616ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:20.02958Z","caller":"traceutil/trace.go:171","msg":"trace[1943425491] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1378; }","duration":"176.19883ms","start":"2026-05-07T13:47:19.853352Z","end":"2026-05-07T13:47:20.029551Z","steps":["trace[1943425491] 'read index received' (duration: 69.693047ms)","trace[1943425491] 'applied index is now lower than readState.Index' (duration: 106.504533ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:47:20.029653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:19.693321Z","time spent":"336.29742ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:47:20.029628Z","caller":"traceutil/trace.go:171","msg":"trace[2007690904] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"129.162762ms","start":"2026-05-07T13:47:19.900434Z","end":"2026-05-07T13:47:20.029597Z","steps":["trace[2007690904] 'process raft request' (duration: 129.061261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:20.029879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.711846ms","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-07T13:47:20.029913Z","caller":"traceutil/trace.go:171","msg":"trace[394253388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1323; }","duration":"117.768257ms","start":"2026-05-07T13:47:19.912136Z","end":"2026-05-07T13:47:20.029904Z","steps":["trace[394253388] 'agreement among raft nodes before linearized reading' (duration: 117.651095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:20.029908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.532973ms","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-07T13:47:20.029968Z","caller":"traceutil/trace.go:171","msg":"trace[495568752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1323; }","duration":"176.640765ms","start":"2026-05-07T13:47:19.853308Z","end":"2026-05-07T13:47:20.029949Z","steps":["trace[495568752] 'agreement among raft nodes before linearized reading' (duration: 176.384992ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:20.209736Z","caller":"traceutil/trace.go:171","msg":"trace[1038011419] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"176.592831ms","start":"2026-05-07T13:47:20.033111Z","end":"2026-05-07T13:47:20.209703Z","steps":["trace[1038011419] 'process raft request' (duration: 96.374332ms)","trace[1038011419] 'compare' (duration: 79.985027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:47:20.523507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.698527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:47:20.523845Z","caller":"traceutil/trace.go:171","msg":"trace[929541495] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"309.257461ms","start":"2026-05-07T13:47:20.21455Z","end":"2026-05-07T13:47:20.523807Z","steps":["trace[929541495] 'process raft request' (duration: 135.155829ms)","trace[929541495] 'compare' (duration: 173.572906ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:47:20.523927Z","caller":"traceutil/trace.go:171","msg":"trace[1816647071] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"267.588298ms","start":"2026-05-07T13:47:20.256318Z","end":"2026-05-07T13:47:20.523906Z","steps":["trace[1816647071] 'process raft request' (duration: 267.297895ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:20.523878Z","caller":"traceutil/trace.go:171","msg":"trace[53687850] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1383; }","duration":"146.12437ms","start":"2026-05-07T13:47:20.377699Z","end":"2026-05-07T13:47:20.523823Z","steps":["trace[53687850] 'read index received' (duration: 144.240739ms)","trace[53687850] 'applied index is now lower than readState.Index' (duration: 1.882461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:47:20.524186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:20.214532Z","time spent":"309.447703ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:47:20.524418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.683033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:47:20.524489Z","caller":"traceutil/trace.go:171","msg":"trace[419682294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"106.822117ms","start":"2026-05-07T13:47:20.417649Z","end":"2026-05-07T13:47:20.524471Z","steps":["trace[419682294] 'agreement among raft nodes before linearized reading' (duration: 106.552652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:20.524837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.102192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-05-07T13:47:20.524932Z","caller":"traceutil/trace.go:171","msg":"trace[1584043535] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1327; }","duration":"147.241995ms","start":"2026-05-07T13:47:20.377672Z","end":"2026-05-07T13:47:20.524914Z","steps":["trace[1584043535] 'agreement among raft nodes before linearized reading' (duration: 146.249652ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:20.712816Z","caller":"traceutil/trace.go:171","msg":"trace[1098170413] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"102.249602ms","start":"2026-05-07T13:47:20.610543Z","end":"2026-05-07T13:47:20.712793Z","steps":["trace[1098170413] 'process raft request' (duration: 102.1314ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:22.678491Z","caller":"traceutil/trace.go:171","msg":"trace[2076354207] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"145.539376ms","start":"2026-05-07T13:47:22.532924Z","end":"2026-05-07T13:47:22.678463Z","steps":["trace[2076354207] 'process raft request' (duration: 144.221381ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:24.288035Z","caller":"traceutil/trace.go:171","msg":"trace[1570916044] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"127.167833ms","start":"2026-05-07T13:47:24.160843Z","end":"2026-05-07T13:47:24.288011Z","steps":["trace[1570916044] 'read index received' (duration: 124.084167ms)","trace[1570916044] 'applied index is now lower than readState.Index' (duration: 3.082506ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:47:24.288053Z","caller":"traceutil/trace.go:171","msg":"trace[744048736] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"175.35461ms","start":"2026-05-07T13:47:24.112676Z","end":"2026-05-07T13:47:24.288031Z","steps":["trace[744048736] 'process raft request' (duration: 172.265124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:24.288194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.339275ms","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-07T13:47:24.288656Z","caller":"traceutil/trace.go:171","msg":"trace[1668588314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"127.850821ms","start":"2026-05-07T13:47:24.160797Z","end":"2026-05-07T13:47:24.288648Z","steps":["trace[1668588314] 'agreement among raft nodes before linearized reading' (duration: 127.303465ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:25.052779Z","caller":"traceutil/trace.go:171","msg":"trace[1352033343] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"276.129982ms","start":"2026-05-07T13:47:24.77663Z","end":"2026-05-07T13:47:25.05276Z","steps":["trace[1352033343] 'process raft request' (duration: 275.98791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:32.737539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398340535930836789,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T13:47:32.973712Z","caller":"traceutil/trace.go:171","msg":"trace[607729864] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"822.521054ms","start":"2026-05-07T13:47:32.151165Z","end":"2026-05-07T13:47:32.973687Z","steps":["trace[607729864] 'process raft request' (duration: 822.380071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:32.973896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:32.151137Z","time spent":"822.680386ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:47:33.001902Z","caller":"traceutil/trace.go:171","msg":"trace[1478936927] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1428; }","duration":"765.339093ms","start":"2026-05-07T13:47:32.236538Z","end":"2026-05-07T13:47:33.001877Z","steps":["trace[1478936927] 'read index received' (duration: 737.131677ms)","trace[1478936927] 'applied index is now lower than readState.Index' (duration: 28.206496ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:47:33.002027Z","caller":"traceutil/trace.go:171","msg":"trace[1952770516] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"687.392341ms","start":"2026-05-07T13:47:32.314614Z","end":"2026-05-07T13:47:33.002006Z","steps":["trace[1952770516] 'process raft request' (duration: 687.147608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:33.002139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:32.314595Z","time spent":"687.459772ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:47:33.00223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.671266ms","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-07T13:47:33.002326Z","caller":"traceutil/trace.go:171","msg":"trace[745079355] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1370; }","duration":"765.828629ms","start":"2026-05-07T13:47:32.23647Z","end":"2026-05-07T13:47:33.002299Z","steps":["trace[745079355] 'agreement among raft nodes before linearized reading' (duration: 765.646356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:33.00239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:32.236447Z","time spent":"765.925789ms","remote":"127.0.0.1:56616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-07T13:47:33.002411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.297722ms","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-07T13:47:33.002449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.859304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T13:47:33.002512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.236158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:47:33.002516Z","caller":"traceutil/trace.go:171","msg":"trace[918692816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"334.999966ms","start":"2026-05-07T13:47:32.6675Z","end":"2026-05-07T13:47:33.0025Z","steps":["trace[918692816] 'agreement among raft nodes before linearized reading' (duration: 334.776943ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:33.002544Z","caller":"traceutil/trace.go:171","msg":"trace[1885873630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1370; }","duration":"584.302289ms","start":"2026-05-07T13:47:32.418231Z","end":"2026-05-07T13:47:33.002534Z","steps":["trace[1885873630] 'agreement among raft nodes before linearized reading' (duration: 584.260059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:33.002554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:32.667476Z","time spent":"335.070946ms","remote":"127.0.0.1:56462","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-07T13:47:33.002574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:32.418213Z","time spent":"584.35482ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T13:47:33.002574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.416488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T13:47:33.002611Z","caller":"traceutil/trace.go:171","msg":"trace[1900434682] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1370; }","duration":"130.49038ms","start":"2026-05-07T13:47:32.87211Z","end":"2026-05-07T13:47:33.002601Z","steps":["trace[1900434682] 'agreement among raft nodes before linearized reading' (duration: 130.418769ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:47:33.002464Z","caller":"traceutil/trace.go:171","msg":"trace[581111717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"669.394223ms","start":"2026-05-07T13:47:32.333055Z","end":"2026-05-07T13:47:33.002449Z","steps":["trace[581111717] 'agreement among raft nodes before linearized reading' (duration: 669.223371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:33.002728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:32.333032Z","time spent":"669.686196ms","remote":"127.0.0.1:56462","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-07T13:47:33.002797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.087728ms","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-07T13:47:33.002827Z","caller":"traceutil/trace.go:171","msg":"trace[1232229324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1370; }","duration":"649.141138ms","start":"2026-05-07T13:47:32.353677Z","end":"2026-05-07T13:47:33.002818Z","steps":["trace[1232229324] 'agreement among raft nodes before linearized reading' (duration: 649.050327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:47:33.002856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:47:32.353665Z","time spent":"649.184119ms","remote":"127.0.0.1:56462","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\" "}