{"level":"warn","ts":"2026-06-24T17:59:59.089409Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T17:59:59.08949Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-06-24T17:59:59.089554Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-24T17:59:59.089566Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-24T17:59:59.089583Z","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-06-24T17:59:59.090194Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-06-24T17:59:59.090265Z","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.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-06-24T17:59:59.096003Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.468456ms"} {"level":"info","ts":"2026-06-24T17:59:59.106553Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-06-24T17:59:59.106623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T17:59:59.106641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-06-24T17:59:59.10665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T17:59:59.106658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-06-24T17:59:59.106688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-06-24T17:59:59.117393Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T17:59:59.119907Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T17:59:59.122231Z","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-06-24T17:59:59.125013Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T17:59:59.125308Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T17:59:59.125462Z","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-06-24T17:59:59.125518Z","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-06-24T17:59:59.12553Z","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-06-24T17:59:59.131446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-06-24T17:59:59.131578Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-06-24T17:59:59.132752Z","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-06-24T17:59:59.132932Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-24T17:59:59.133015Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-06-24T17:59:59.133967Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T17:59:59.13402Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T17:59:59.706942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T17:59:59.707056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T17:59:59.70709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-06-24T17:59:59.707114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-06-24T17:59:59.707127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-24T17:59:59.707151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-06-24T17:59:59.707174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-06-24T17:59:59.710071Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T17:59:59.710131Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:59:59.710656Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:59:59.713221Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T17:59:59.71491Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-06-24T17:59:59.715686Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:59:59.715807Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:59:59.715855Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T17:59:59.717206Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T17:59:59.717246Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T17:59:59.718642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-24T18:00:04.766768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.194795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-24T18:00:04.766887Z","caller":"traceutil/trace.go:171","msg":"trace[1350609078] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:240; }","duration":"118.36594ms","start":"2026-06-24T18:00:04.648495Z","end":"2026-06-24T18:00:04.766861Z","steps":["trace[1350609078] 'range keys from in-memory index tree' (duration: 118.02715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:05.293262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.106205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:00:05.293346Z","caller":"traceutil/trace.go:171","msg":"trace[1003559174] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"242.936536ms","start":"2026-06-24T18:00:05.050395Z","end":"2026-06-24T18:00:05.293332Z","steps":["trace[1003559174] 'read index received' (duration: 140.364759ms)","trace[1003559174] 'applied index is now lower than readState.Index' (duration: 102.570917ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:05.293455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.022617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-24T18:00:05.293422Z","caller":"traceutil/trace.go:171","msg":"trace[1067754088] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"266.654421ms","start":"2026-06-24T18:00:05.026721Z","end":"2026-06-24T18:00:05.293375Z","steps":["trace[1067754088] 'process raft request' (duration: 163.983331ms)","trace[1067754088] 'compare' (duration: 101.998312ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:05.293481Z","caller":"traceutil/trace.go:171","msg":"trace[362382959] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:245; }","duration":"268.09779ms","start":"2026-06-24T18:00:05.025376Z","end":"2026-06-24T18:00:05.293474Z","steps":["trace[362382959] 'agreement among raft nodes before linearized reading' (duration: 268.011767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:05.293513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.963826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-06-24T18:00:05.293591Z","caller":"traceutil/trace.go:171","msg":"trace[1363198577] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:245; }","duration":"268.086589ms","start":"2026-06-24T18:00:05.025486Z","end":"2026-06-24T18:00:05.293572Z","steps":["trace[1363198577] 'agreement among raft nodes before linearized reading' (duration: 267.887574ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.300583Z","caller":"traceutil/trace.go:171","msg":"trace[469294302] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"247.134579ms","start":"2026-06-24T18:00:05.053426Z","end":"2026-06-24T18:00:05.300561Z","steps":["trace[469294302] 'process raft request' (duration: 246.982775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:05.300815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.074895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:05.300879Z","caller":"traceutil/trace.go:171","msg":"trace[1510202065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:250; }","duration":"223.177298ms","start":"2026-06-24T18:00:05.077686Z","end":"2026-06-24T18:00:05.300864Z","steps":["trace[1510202065] 'agreement among raft nodes before linearized reading' (duration: 223.043924ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.300958Z","caller":"traceutil/trace.go:171","msg":"trace[134316361] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"246.984745ms","start":"2026-06-24T18:00:05.053953Z","end":"2026-06-24T18:00:05.300937Z","steps":["trace[134316361] 'process raft request' (duration: 246.556503ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.300957Z","caller":"traceutil/trace.go:171","msg":"trace[10632599] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"198.407544ms","start":"2026-06-24T18:00:05.102513Z","end":"2026-06-24T18:00:05.300921Z","steps":["trace[10632599] 'process raft request' (duration: 198.105316ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.300971Z","caller":"traceutil/trace.go:171","msg":"trace[1589066501] transaction","detail":"{read_only:false; number_of_response:0; response_revision:247; }","duration":"212.984554ms","start":"2026-06-24T18:00:05.087977Z","end":"2026-06-24T18:00:05.300961Z","steps":["trace[1589066501] 'process raft request' (duration: 212.569183ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.300944Z","caller":"traceutil/trace.go:171","msg":"trace[1707638798] transaction","detail":"{read_only:false; number_of_response:0; response_revision:247; }","duration":"199.871213ms","start":"2026-06-24T18:00:05.101017Z","end":"2026-06-24T18:00:05.300888Z","steps":["trace[1707638798] 'process raft request' (duration: 199.582276ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.301014Z","caller":"traceutil/trace.go:171","msg":"trace[1244332016] transaction","detail":"{read_only:false; number_of_response:0; response_revision:247; }","duration":"200.343756ms","start":"2026-06-24T18:00:05.100662Z","end":"2026-06-24T18:00:05.301006Z","steps":["trace[1244332016] 'process raft request' (duration: 199.917744ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.301034Z","caller":"traceutil/trace.go:171","msg":"trace[1783398668] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"198.430155ms","start":"2026-06-24T18:00:05.102583Z","end":"2026-06-24T18:00:05.301013Z","steps":["trace[1783398668] 'process raft request' (duration: 198.092226ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.301092Z","caller":"traceutil/trace.go:171","msg":"trace[1977420963] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"197.629553ms","start":"2026-06-24T18:00:05.103447Z","end":"2026-06-24T18:00:05.301077Z","steps":["trace[1977420963] 'process raft request' (duration: 197.252993ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.459242Z","caller":"traceutil/trace.go:171","msg":"trace[1537996596] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"117.695752ms","start":"2026-06-24T18:00:05.341523Z","end":"2026-06-24T18:00:05.459218Z","steps":["trace[1537996596] 'read index received' (duration: 58.6182ms)","trace[1537996596] 'applied index is now lower than readState.Index' (duration: 59.076602ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:05.459322Z","caller":"traceutil/trace.go:171","msg":"trace[1959864770] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"118.579885ms","start":"2026-06-24T18:00:05.340713Z","end":"2026-06-24T18:00:05.459293Z","steps":["trace[1959864770] 'process raft request' (duration: 59.413191ms)","trace[1959864770] 'compare' (duration: 58.957889ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:05.459446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.304609ms","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-06-24T18:00:05.459539Z","caller":"traceutil/trace.go:171","msg":"trace[1966202225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:256; }","duration":"124.422641ms","start":"2026-06-24T18:00:05.335097Z","end":"2026-06-24T18:00:05.45952Z","steps":["trace[1966202225] 'agreement among raft nodes before linearized reading' (duration: 124.229886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:05.45961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.996717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-24T18:00:05.459716Z","caller":"traceutil/trace.go:171","msg":"trace[131088327] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:256; }","duration":"125.179042ms","start":"2026-06-24T18:00:05.334516Z","end":"2026-06-24T18:00:05.459695Z","steps":["trace[131088327] 'agreement among raft nodes before linearized reading' (duration: 124.955966ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.461791Z","caller":"traceutil/trace.go:171","msg":"trace[1239020440] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"117.724773ms","start":"2026-06-24T18:00:05.344049Z","end":"2026-06-24T18:00:05.461774Z","steps":["trace[1239020440] 'process raft request' (duration: 117.363783ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:05.461848Z","caller":"traceutil/trace.go:171","msg":"trace[626993957] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"108.733962ms","start":"2026-06-24T18:00:05.353087Z","end":"2026-06-24T18:00:05.461821Z","steps":["trace[626993957] 'process raft request' (duration: 108.432614ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:11.668503Z","caller":"traceutil/trace.go:171","msg":"trace[1002999553] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"112.33698ms","start":"2026-06-24T18:00:11.556143Z","end":"2026-06-24T18:00:11.66848Z","steps":["trace[1002999553] 'read index received' (duration: 112.245428ms)","trace[1002999553] 'applied index is now lower than readState.Index' (duration: 90.472µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:11.668746Z","caller":"traceutil/trace.go:171","msg":"trace[1716088831] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"169.464018ms","start":"2026-06-24T18:00:11.499274Z","end":"2026-06-24T18:00:11.668738Z","steps":["trace[1716088831] 'process raft request' (duration: 169.079328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:11.668879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.732319ms","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-06-24T18:00:11.668902Z","caller":"traceutil/trace.go:171","msg":"trace[806626940] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:356; }","duration":"112.801121ms","start":"2026-06-24T18:00:11.556095Z","end":"2026-06-24T18:00:11.668896Z","steps":["trace[806626940] 'agreement among raft nodes before linearized reading' (duration: 112.74995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:12.254746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.486922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:00:12.255Z","caller":"traceutil/trace.go:171","msg":"trace[174223873] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:371; }","duration":"372.030787ms","start":"2026-06-24T18:00:11.882935Z","end":"2026-06-24T18:00:12.254966Z","steps":["trace[174223873] 'read index received' (duration: 265.191426ms)","trace[174223873] 'applied index is now lower than readState.Index' (duration: 106.83804ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:12.2551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.17003ms","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-06-24T18:00:12.255139Z","caller":"traceutil/trace.go:171","msg":"trace[1195781349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:359; }","duration":"372.234731ms","start":"2026-06-24T18:00:11.882883Z","end":"2026-06-24T18:00:12.255118Z","steps":["trace[1195781349] 'agreement among raft nodes before linearized reading' (duration: 372.156449ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:12.255156Z","caller":"traceutil/trace.go:171","msg":"trace[426181507] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"582.697077ms","start":"2026-06-24T18:00:11.672402Z","end":"2026-06-24T18:00:12.255099Z","steps":["trace[426181507] 'process raft request' (duration: 582.445991ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:12.25524Z","caller":"traceutil/trace.go:171","msg":"trace[1540932420] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"564.556199ms","start":"2026-06-24T18:00:11.690674Z","end":"2026-06-24T18:00:12.25523Z","steps":["trace[1540932420] 'process raft request' (duration: 564.225111ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:12.255205Z","caller":"traceutil/trace.go:171","msg":"trace[357202332] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"583.647759ms","start":"2026-06-24T18:00:11.671505Z","end":"2026-06-24T18:00:12.255153Z","steps":["trace[357202332] 'process raft request' (duration: 476.545512ms)","trace[357202332] 'compare' (duration: 106.37321ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:12.255294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:11.690658Z","time spent":"564.605711ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:00:12.255172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:11.882862Z","time spent":"372.302213ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T18:00:12.255394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:11.671488Z","time spent":"583.803293ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:00:12.255926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:11.672395Z","time spent":"582.85822ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:17.883726Z","caller":"traceutil/trace.go:171","msg":"trace[1250513403] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"185.196016ms","start":"2026-06-24T18:00:17.698506Z","end":"2026-06-24T18:00:17.883702Z","steps":["trace[1250513403] 'process raft request' (duration: 185.033522ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:18.431211Z","caller":"traceutil/trace.go:171","msg":"trace[775474480] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"147.858651ms","start":"2026-06-24T18:00:18.28331Z","end":"2026-06-24T18:00:18.431169Z","steps":["trace[775474480] 'read index received' (duration: 147.603074ms)","trace[775474480] 'applied index is now lower than readState.Index' (duration: 254.637µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:18.431264Z","caller":"traceutil/trace.go:171","msg":"trace[1646487009] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"149.10528ms","start":"2026-06-24T18:00:18.282126Z","end":"2026-06-24T18:00:18.431232Z","steps":["trace[1646487009] 'process raft request' (duration: 148.896705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:18.431376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.041745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:18.431421Z","caller":"traceutil/trace.go:171","msg":"trace[2134528466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"148.131336ms","start":"2026-06-24T18:00:18.283277Z","end":"2026-06-24T18:00:18.431409Z","steps":["trace[2134528466] 'agreement among raft nodes before linearized reading' (duration: 148.046674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:18.43145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.84556ms","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-06-24T18:00:18.431536Z","caller":"traceutil/trace.go:171","msg":"trace[1176219733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"142.964743ms","start":"2026-06-24T18:00:18.288553Z","end":"2026-06-24T18:00:18.431518Z","steps":["trace[1176219733] 'agreement among raft nodes before linearized reading' (duration: 142.8388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:18.431664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.883839ms","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-06-24T18:00:18.431697Z","caller":"traceutil/trace.go:171","msg":"trace[922999401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"134.94927ms","start":"2026-06-24T18:00:18.296738Z","end":"2026-06-24T18:00:18.431687Z","steps":["trace[922999401] 'agreement among raft nodes before linearized reading' (duration: 134.901249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:23.172224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.096618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-06-24T18:00:23.172329Z","caller":"traceutil/trace.go:171","msg":"trace[768067440] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:389; }","duration":"136.251132ms","start":"2026-06-24T18:00:23.036054Z","end":"2026-06-24T18:00:23.172305Z","steps":["trace[768067440] 'range keys from in-memory index tree' (duration: 135.997586ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:30.646515Z","caller":"traceutil/trace.go:171","msg":"trace[1565660250] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"101.20833ms","start":"2026-06-24T18:00:30.545265Z","end":"2026-06-24T18:00:30.646473Z","steps":["trace[1565660250] 'process raft request' (duration: 24.949709ms)","trace[1565660250] 'compare' (duration: 76.084007ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:36.133715Z","caller":"traceutil/trace.go:171","msg":"trace[1529818879] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:495; }","duration":"145.685598ms","start":"2026-06-24T18:00:35.988003Z","end":"2026-06-24T18:00:36.133689Z","steps":["trace[1529818879] 'read index received' (duration: 69.236512ms)","trace[1529818879] 'applied index is now lower than readState.Index' (duration: 76.447945ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:36.133831Z","caller":"traceutil/trace.go:171","msg":"trace[269637609] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"199.741116ms","start":"2026-06-24T18:00:35.934079Z","end":"2026-06-24T18:00:36.13382Z","steps":["trace[269637609] 'process raft request' (duration: 199.551391ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:36.133969Z","caller":"traceutil/trace.go:171","msg":"trace[1222857005] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"202.250126ms","start":"2026-06-24T18:00:35.931664Z","end":"2026-06-24T18:00:36.133914Z","steps":["trace[1222857005] 'process raft request' (duration: 125.562585ms)","trace[1222857005] 'compare' (duration: 76.25239ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:36.134165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.802803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"warn","ts":"2026-06-24T18:00:36.133988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.049663ms","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-06-24T18:00:36.13441Z","caller":"traceutil/trace.go:171","msg":"trace[454692756] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:475; }","duration":"194.157641ms","start":"2026-06-24T18:00:35.940234Z","end":"2026-06-24T18:00:36.134391Z","steps":["trace[454692756] 'agreement among raft nodes before linearized reading' (duration: 193.68058ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:36.134412Z","caller":"traceutil/trace.go:171","msg":"trace[1875515887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"155.549794ms","start":"2026-06-24T18:00:35.97884Z","end":"2026-06-24T18:00:36.13439Z","steps":["trace[1875515887] 'agreement among raft nodes before linearized reading' (duration: 155.040572ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:36.153612Z","caller":"traceutil/trace.go:171","msg":"trace[1753400860] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"158.148447ms","start":"2026-06-24T18:00:35.995448Z","end":"2026-06-24T18:00:36.153596Z","steps":["trace[1753400860] 'process raft request' (duration: 158.007514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:36.153705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.441278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-06-24T18:00:36.153801Z","caller":"traceutil/trace.go:171","msg":"trace[2143586841] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:476; }","duration":"159.576622ms","start":"2026-06-24T18:00:35.994204Z","end":"2026-06-24T18:00:36.153781Z","steps":["trace[2143586841] 'agreement among raft nodes before linearized reading' (duration: 159.372047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:36.154086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.005561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-06-24T18:00:36.154153Z","caller":"traceutil/trace.go:171","msg":"trace[869866088] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:476; }","duration":"160.166615ms","start":"2026-06-24T18:00:35.993969Z","end":"2026-06-24T18:00:36.154135Z","steps":["trace[869866088] 'agreement among raft nodes before linearized reading' (duration: 159.789576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:36.469744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.586851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:00:36.469978Z","caller":"traceutil/trace.go:171","msg":"trace[1604396897] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"307.306409ms","start":"2026-06-24T18:00:36.162642Z","end":"2026-06-24T18:00:36.469949Z","steps":["trace[1604396897] 'process raft request' (duration: 177.43238ms)","trace[1604396897] 'compare' (duration: 129.485689ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:36.470063Z","caller":"traceutil/trace.go:171","msg":"trace[1557763160] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"261.145561ms","start":"2026-06-24T18:00:36.208901Z","end":"2026-06-24T18:00:36.470047Z","steps":["trace[1557763160] 'process raft request' (duration: 261.024128ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:36.470074Z","caller":"traceutil/trace.go:171","msg":"trace[2073142751] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"278.948888ms","start":"2026-06-24T18:00:36.191107Z","end":"2026-06-24T18:00:36.470056Z","steps":["trace[2073142751] 'read index received' (duration: 148.979047ms)","trace[2073142751] 'applied index is now lower than readState.Index' (duration: 129.968261ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:36.470122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:36.162624Z","time spent":"307.421762ms","remote":"127.0.0.1:44976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4656,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:00:36.470256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.565577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4354"} {"level":"info","ts":"2026-06-24T18:00:36.470303Z","caller":"traceutil/trace.go:171","msg":"trace[1649418112] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:479; }","duration":"310.613618ms","start":"2026-06-24T18:00:36.159664Z","end":"2026-06-24T18:00:36.470277Z","steps":["trace[1649418112] 'agreement among raft nodes before linearized reading' (duration: 310.487955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:36.470297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.331765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-06-24T18:00:36.470283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.324604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:00:36.470333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.533647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xb4gp\" ","response":"range_response_count:1 size:4679"} {"level":"warn","ts":"2026-06-24T18:00:36.470338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:36.159657Z","time spent":"310.67247ms","remote":"127.0.0.1:45006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":4377,"request content":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" "} {"level":"info","ts":"2026-06-24T18:00:36.470344Z","caller":"traceutil/trace.go:171","msg":"trace[2047743591] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:479; }","duration":"266.397967ms","start":"2026-06-24T18:00:36.203931Z","end":"2026-06-24T18:00:36.470329Z","steps":["trace[2047743591] 'agreement among raft nodes before linearized reading' (duration: 266.292974ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:36.470372Z","caller":"traceutil/trace.go:171","msg":"trace[757418447] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-xb4gp; range_end:; response_count:1; response_revision:479; }","duration":"315.582547ms","start":"2026-06-24T18:00:36.154779Z","end":"2026-06-24T18:00:36.470362Z","steps":["trace[757418447] 'agreement among raft nodes before linearized reading' (duration: 315.449824ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:36.470365Z","caller":"traceutil/trace.go:171","msg":"trace[2084386185] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"186.437897ms","start":"2026-06-24T18:00:36.283907Z","end":"2026-06-24T18:00:36.470344Z","steps":["trace[2084386185] 'agreement among raft nodes before linearized reading' (duration: 186.293864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:36.470402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:36.15477Z","time spent":"315.626288ms","remote":"127.0.0.1:44718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4702,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xb4gp\" "} {"level":"warn","ts":"2026-06-24T18:00:36.470614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.650972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-06-24T18:00:36.470661Z","caller":"traceutil/trace.go:171","msg":"trace[1797017049] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:479; }","duration":"266.715794ms","start":"2026-06-24T18:00:36.203931Z","end":"2026-06-24T18:00:36.470647Z","steps":["trace[1797017049] 'agreement among raft nodes before linearized reading' (duration: 266.254603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:36.470735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.997839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-06-24T18:00:36.470818Z","caller":"traceutil/trace.go:171","msg":"trace[313689729] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:479; }","duration":"209.128432ms","start":"2026-06-24T18:00:36.261661Z","end":"2026-06-24T18:00:36.47079Z","steps":["trace[313689729] 'agreement among raft nodes before linearized reading' (duration: 208.562658ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.072935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.383334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:00:38.073175Z","caller":"traceutil/trace.go:171","msg":"trace[134994201] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"171.154599ms","start":"2026-06-24T18:00:37.901973Z","end":"2026-06-24T18:00:38.073128Z","steps":["trace[134994201] 'process raft request' (duration: 64.494708ms)","trace[134994201] 'compare' (duration: 106.251661ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:38.073258Z","caller":"traceutil/trace.go:171","msg":"trace[1997110219] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"161.265462ms","start":"2026-06-24T18:00:37.911978Z","end":"2026-06-24T18:00:38.073243Z","steps":["trace[1997110219] 'process raft request' (duration: 161.125268ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.073257Z","caller":"traceutil/trace.go:171","msg":"trace[612166951] transaction","detail":"{read_only:false; number_of_response:0; response_revision:507; }","duration":"166.506067ms","start":"2026-06-24T18:00:37.906722Z","end":"2026-06-24T18:00:38.073228Z","steps":["trace[612166951] 'process raft request' (duration: 166.352014ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.073452Z","caller":"traceutil/trace.go:171","msg":"trace[972679733] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"159.427438ms","start":"2026-06-24T18:00:37.913997Z","end":"2026-06-24T18:00:38.073424Z","steps":["trace[972679733] 'process raft request' (duration: 159.210013ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.073626Z","caller":"traceutil/trace.go:171","msg":"trace[324044569] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:531; }","duration":"139.314886ms","start":"2026-06-24T18:00:37.934296Z","end":"2026-06-24T18:00:38.073611Z","steps":["trace[324044569] 'read index received' (duration: 32.186563ms)","trace[324044569] 'applied index is now lower than readState.Index' (duration: 107.127203ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:38.073657Z","caller":"traceutil/trace.go:171","msg":"trace[1253691551] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"149.977231ms","start":"2026-06-24T18:00:37.923669Z","end":"2026-06-24T18:00:38.073646Z","steps":["trace[1253691551] 'process raft request' (duration: 149.774676ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.073171Z","caller":"traceutil/trace.go:171","msg":"trace[155745001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:507; }","duration":"166.481217ms","start":"2026-06-24T18:00:37.906644Z","end":"2026-06-24T18:00:38.073126Z","steps":["trace[155745001] 'process raft request' (duration: 166.398035ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.073698Z","caller":"traceutil/trace.go:171","msg":"trace[199115249] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"143.989177ms","start":"2026-06-24T18:00:37.92969Z","end":"2026-06-24T18:00:38.073679Z","steps":["trace[199115249] 'process raft request' (duration: 143.862614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.073826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.50086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:38.073923Z","caller":"traceutil/trace.go:171","msg":"trace[905608384] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:511; }","duration":"139.612143ms","start":"2026-06-24T18:00:37.934252Z","end":"2026-06-24T18:00:38.073865Z","steps":["trace[905608384] 'agreement among raft nodes before linearized reading' (duration: 139.443569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.157196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.347019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:38.157287Z","caller":"traceutil/trace.go:171","msg":"trace[1121794714] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:512; }","duration":"222.529383ms","start":"2026-06-24T18:00:37.934738Z","end":"2026-06-24T18:00:38.157267Z","steps":["trace[1121794714] 'agreement among raft nodes before linearized reading' (duration: 222.275947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.157513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.084587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:38.157609Z","caller":"traceutil/trace.go:171","msg":"trace[1331559663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:512; }","duration":"223.21406ms","start":"2026-06-24T18:00:37.934357Z","end":"2026-06-24T18:00:38.157571Z","steps":["trace[1331559663] 'agreement among raft nodes before linearized reading' (duration: 223.025405ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.157586Z","caller":"traceutil/trace.go:171","msg":"trace[1427784929] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"186.488948ms","start":"2026-06-24T18:00:37.97106Z","end":"2026-06-24T18:00:38.157549Z","steps":["trace[1427784929] 'process raft request' (duration: 185.71738ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.243251Z","caller":"traceutil/trace.go:171","msg":"trace[231870263] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"156.845576ms","start":"2026-06-24T18:00:38.086376Z","end":"2026-06-24T18:00:38.243222Z","steps":["trace[231870263] 'process raft request' (duration: 155.131155ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.243585Z","caller":"traceutil/trace.go:171","msg":"trace[317703568] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"156.833496ms","start":"2026-06-24T18:00:38.086711Z","end":"2026-06-24T18:00:38.243544Z","steps":["trace[317703568] 'process raft request' (duration: 156.395775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.243614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.07372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-06-24T18:00:38.243727Z","caller":"traceutil/trace.go:171","msg":"trace[348698660] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:514; }","duration":"169.291035ms","start":"2026-06-24T18:00:38.074413Z","end":"2026-06-24T18:00:38.243704Z","steps":["trace[348698660] 'agreement among raft nodes before linearized reading' (duration: 169.048829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.24391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.404497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-06-24T18:00:38.244452Z","caller":"traceutil/trace.go:171","msg":"trace[1643852148] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:514; }","duration":"170.211757ms","start":"2026-06-24T18:00:38.074215Z","end":"2026-06-24T18:00:38.244427Z","steps":["trace[1643852148] 'agreement among raft nodes before linearized reading' (duration: 169.314086ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.497652Z","caller":"traceutil/trace.go:171","msg":"trace[1469173488] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:541; }","duration":"254.44416ms","start":"2026-06-24T18:00:38.243184Z","end":"2026-06-24T18:00:38.497628Z","steps":["trace[1469173488] 'read index received' (duration: 230.629938ms)","trace[1469173488] 'applied index is now lower than readState.Index' (duration: 23.813412ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:38.497766Z","caller":"traceutil/trace.go:171","msg":"trace[1785744509] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"270.917835ms","start":"2026-06-24T18:00:38.226814Z","end":"2026-06-24T18:00:38.497732Z","steps":["trace[1785744509] 'process raft request' (duration: 270.69381ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.497691Z","caller":"traceutil/trace.go:171","msg":"trace[1936304246] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"277.750859ms","start":"2026-06-24T18:00:38.219912Z","end":"2026-06-24T18:00:38.497663Z","steps":["trace[1936304246] 'process raft request' (duration: 277.457802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.498031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.983605ms","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-06-24T18:00:38.498176Z","caller":"traceutil/trace.go:171","msg":"trace[164145965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"303.170429ms","start":"2026-06-24T18:00:38.194983Z","end":"2026-06-24T18:00:38.498154Z","steps":["trace[164145965] 'agreement among raft nodes before linearized reading' (duration: 302.754469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.498228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:38.19496Z","time spent":"303.256972ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T18:00:38.506339Z","caller":"traceutil/trace.go:171","msg":"trace[1535019818] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"250.639638ms","start":"2026-06-24T18:00:38.255678Z","end":"2026-06-24T18:00:38.506318Z","steps":["trace[1535019818] 'process raft request' (duration: 250.432773ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.506979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.665205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:38.507042Z","caller":"traceutil/trace.go:171","msg":"trace[1504365570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"224.775467ms","start":"2026-06-24T18:00:38.282255Z","end":"2026-06-24T18:00:38.50703Z","steps":["trace[1504365570] 'agreement among raft nodes before linearized reading' (duration: 224.626014ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.507403Z","caller":"traceutil/trace.go:171","msg":"trace[1893484276] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"246.424957ms","start":"2026-06-24T18:00:38.260964Z","end":"2026-06-24T18:00:38.507389Z","steps":["trace[1893484276] 'process raft request' (duration: 245.29912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.507537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.581133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:00:38.507534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.253369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:38.507576Z","caller":"traceutil/trace.go:171","msg":"trace[1357346766] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:518; }","duration":"137.640504ms","start":"2026-06-24T18:00:38.369925Z","end":"2026-06-24T18:00:38.507565Z","steps":["trace[1357346766] 'agreement among raft nodes before linearized reading' (duration: 137.576853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.507609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.263969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:38.507688Z","caller":"traceutil/trace.go:171","msg":"trace[820749494] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:518; }","duration":"138.378432ms","start":"2026-06-24T18:00:38.369297Z","end":"2026-06-24T18:00:38.507676Z","steps":["trace[820749494] 'agreement among raft nodes before linearized reading' (duration: 138.222048ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.507768Z","caller":"traceutil/trace.go:171","msg":"trace[262822798] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:518; }","duration":"138.366322ms","start":"2026-06-24T18:00:38.369209Z","end":"2026-06-24T18:00:38.507575Z","steps":["trace[262822798] 'agreement among raft nodes before linearized reading' (duration: 138.265969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.825966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.191946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:00:38.826067Z","caller":"traceutil/trace.go:171","msg":"trace[183817516] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"186.649262ms","start":"2026-06-24T18:00:38.639405Z","end":"2026-06-24T18:00:38.826054Z","steps":["trace[183817516] 'read index received' (duration: 75.861301ms)","trace[183817516] 'applied index is now lower than readState.Index' (duration: 110.786781ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:38.826134Z","caller":"traceutil/trace.go:171","msg":"trace[1002133420] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"197.592595ms","start":"2026-06-24T18:00:38.628507Z","end":"2026-06-24T18:00:38.826099Z","steps":["trace[1002133420] 'process raft request' (duration: 86.749743ms)","trace[1002133420] 'compare' (duration: 110.066823ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:38.826264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.858007ms","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-06-24T18:00:38.826295Z","caller":"traceutil/trace.go:171","msg":"trace[901467338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"186.923838ms","start":"2026-06-24T18:00:38.639363Z","end":"2026-06-24T18:00:38.826287Z","steps":["trace[901467338] 'agreement among raft nodes before linearized reading' (duration: 186.774435ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.896772Z","caller":"traceutil/trace.go:171","msg":"trace[1862207676] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"248.862195ms","start":"2026-06-24T18:00:38.647888Z","end":"2026-06-24T18:00:38.896751Z","steps":["trace[1862207676] 'process raft request' (duration: 248.457405ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.897049Z","caller":"traceutil/trace.go:171","msg":"trace[1118794122] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"211.293543ms","start":"2026-06-24T18:00:38.685749Z","end":"2026-06-24T18:00:38.897043Z","steps":["trace[1118794122] 'process raft request' (duration: 210.940174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:38.897071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.413188ms","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-06-24T18:00:38.89718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.30853ms","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-06-24T18:00:38.897204Z","caller":"traceutil/trace.go:171","msg":"trace[1423090688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"200.363911ms","start":"2026-06-24T18:00:38.696834Z","end":"2026-06-24T18:00:38.897198Z","steps":["trace[1423090688] 'agreement among raft nodes before linearized reading' (duration: 200.269478ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.89712Z","caller":"traceutil/trace.go:171","msg":"trace[907406389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"229.50075ms","start":"2026-06-24T18:00:38.667606Z","end":"2026-06-24T18:00:38.897107Z","steps":["trace[907406389] 'agreement among raft nodes before linearized reading' (duration: 229.292395ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.95352Z","caller":"traceutil/trace.go:171","msg":"trace[1021651498] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"114.95958ms","start":"2026-06-24T18:00:38.838534Z","end":"2026-06-24T18:00:38.953494Z","steps":["trace[1021651498] 'process raft request' (duration: 114.876778ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:38.954039Z","caller":"traceutil/trace.go:171","msg":"trace[2091565975] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"123.621898ms","start":"2026-06-24T18:00:38.830403Z","end":"2026-06-24T18:00:38.954025Z","steps":["trace[2091565975] 'process raft request' (duration: 107.719687ms)","trace[2091565975] 'compare' (duration: 15.123082ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:39.655117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.663499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:00:39.6554Z","caller":"traceutil/trace.go:171","msg":"trace[1325428223] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"671.936614ms","start":"2026-06-24T18:00:38.983432Z","end":"2026-06-24T18:00:39.655368Z","steps":["trace[1325428223] 'process raft request' (duration: 325.926726ms)","trace[1325428223] 'compare' (duration: 345.480565ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:39.65547Z","caller":"traceutil/trace.go:171","msg":"trace[1714359259] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:564; }","duration":"370.450235ms","start":"2026-06-24T18:00:39.284999Z","end":"2026-06-24T18:00:39.65545Z","steps":["trace[1714359259] 'read index received' (duration: 24.371016ms)","trace[1714359259] 'applied index is now lower than readState.Index' (duration: 346.078079ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:39.65555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:38.983357Z","time spent":"672.104637ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:00:39.65556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.563027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:39.655533Z","caller":"traceutil/trace.go:171","msg":"trace[2040971504] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"648.779608ms","start":"2026-06-24T18:00:39.006714Z","end":"2026-06-24T18:00:39.655493Z","steps":["trace[2040971504] 'process raft request' (duration: 648.611554ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:39.655524Z","caller":"traceutil/trace.go:171","msg":"trace[1038264072] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"656.729178ms","start":"2026-06-24T18:00:38.998734Z","end":"2026-06-24T18:00:39.655463Z","steps":["trace[1038264072] 'process raft request' (duration: 656.517343ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:39.655601Z","caller":"traceutil/trace.go:171","msg":"trace[806362519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"370.632639ms","start":"2026-06-24T18:00:39.284957Z","end":"2026-06-24T18:00:39.65559Z","steps":["trace[806362519] 'agreement among raft nodes before linearized reading' (duration: 370.549837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:39.655642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:39.284937Z","time spent":"370.69624ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:00:39.655708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:39.006693Z","time spent":"648.923791ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:00:39.655763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:38.998437Z","time spent":"657.2156ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T18:00:40.554549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.601355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:00:40.555098Z","caller":"traceutil/trace.go:171","msg":"trace[369862857] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"801.602317ms","start":"2026-06-24T18:00:39.753463Z","end":"2026-06-24T18:00:40.555065Z","steps":["trace[369862857] 'process raft request' (duration: 164.348326ms)","trace[369862857] 'compare' (duration: 636.347169ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:40.555302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:39.753442Z","time spent":"801.74897ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:40.55729Z","caller":"traceutil/trace.go:171","msg":"trace[301453917] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"792.932678ms","start":"2026-06-24T18:00:39.762926Z","end":"2026-06-24T18:00:40.555859Z","steps":["trace[301453917] 'process raft request' (duration: 792.17788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:40.559488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:39.762889Z","time spent":"796.507604ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:40.609474Z","caller":"traceutil/trace.go:171","msg":"trace[38557440] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"660.821786ms","start":"2026-06-24T18:00:39.94844Z","end":"2026-06-24T18:00:40.609262Z","steps":["trace[38557440] 'process raft request' (duration: 660.353195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:40.61121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:39.948408Z","time spent":"661.249737ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T18:00:40.913551Z","caller":"traceutil/trace.go:171","msg":"trace[112663543] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"335.411364ms","start":"2026-06-24T18:00:40.578104Z","end":"2026-06-24T18:00:40.913516Z","steps":["trace[112663543] 'process raft request' (duration: 256.295174ms)","trace[112663543] 'compare' (duration: 78.839373ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:40.913717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:40.578071Z","time spent":"335.555036ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:41.181314Z","caller":"traceutil/trace.go:171","msg":"trace[567559128] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"567.978608ms","start":"2026-06-24T18:00:40.613307Z","end":"2026-06-24T18:00:41.181286Z","steps":["trace[567559128] 'process raft request' (duration: 561.301227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:41.18145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:40.61328Z","time spent":"568.098421ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:41.18166Z","caller":"traceutil/trace.go:171","msg":"trace[1721139350] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"545.703783ms","start":"2026-06-24T18:00:40.635925Z","end":"2026-06-24T18:00:41.181628Z","steps":["trace[1721139350] 'process raft request' (duration: 545.350734ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:41.181773Z","caller":"traceutil/trace.go:171","msg":"trace[138210134] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"453.255033ms","start":"2026-06-24T18:00:40.728501Z","end":"2026-06-24T18:00:41.181756Z","steps":["trace[138210134] 'process raft request' (duration: 453.001997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:41.181846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:40.728476Z","time spent":"453.337805ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T18:00:41.182151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:40.635897Z","time spent":"545.846067ms","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:41.224831Z","caller":"traceutil/trace.go:171","msg":"trace[1973074121] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:573; }","duration":"264.979772ms","start":"2026-06-24T18:00:40.959829Z","end":"2026-06-24T18:00:41.224809Z","steps":["trace[1973074121] 'read index received' (duration: 214.871279ms)","trace[1973074121] 'applied index is now lower than readState.Index' (duration: 50.107753ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:41.224976Z","caller":"traceutil/trace.go:171","msg":"trace[185508338] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"285.116236ms","start":"2026-06-24T18:00:40.939848Z","end":"2026-06-24T18:00:41.224964Z","steps":["trace[185508338] 'process raft request' (duration: 284.770548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:41.225287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.449584ms","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-06-24T18:00:41.225329Z","caller":"traceutil/trace.go:171","msg":"trace[384661423] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"265.587847ms","start":"2026-06-24T18:00:40.959731Z","end":"2026-06-24T18:00:41.225319Z","steps":["trace[384661423] 'agreement among raft nodes before linearized reading' (duration: 265.435063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:41.23101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.471815ms","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-06-24T18:00:41.231071Z","caller":"traceutil/trace.go:171","msg":"trace[1949177859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"270.607408ms","start":"2026-06-24T18:00:40.960451Z","end":"2026-06-24T18:00:41.231059Z","steps":["trace[1949177859] 'agreement among raft nodes before linearized reading' (duration: 270.399533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:41.231251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.589175ms","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-06-24T18:00:41.231305Z","caller":"traceutil/trace.go:171","msg":"trace[2087741162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"267.662217ms","start":"2026-06-24T18:00:40.963628Z","end":"2026-06-24T18:00:41.231291Z","steps":["trace[2087741162] 'agreement among raft nodes before linearized reading' (duration: 267.514954ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:46.437199Z","caller":"traceutil/trace.go:171","msg":"trace[2141593240] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"152.8278ms","start":"2026-06-24T18:00:46.284344Z","end":"2026-06-24T18:00:46.437172Z","steps":["trace[2141593240] 'read index received' (duration: 56.689782ms)","trace[2141593240] 'applied index is now lower than readState.Index' (duration: 96.136628ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:46.437241Z","caller":"traceutil/trace.go:171","msg":"trace[926962038] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"249.308256ms","start":"2026-06-24T18:00:46.187886Z","end":"2026-06-24T18:00:46.437194Z","steps":["trace[926962038] 'process raft request' (duration: 153.224529ms)","trace[926962038] 'compare' (duration: 95.878693ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:46.43734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.980563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:46.437381Z","caller":"traceutil/trace.go:171","msg":"trace[652800771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"153.041675ms","start":"2026-06-24T18:00:46.284329Z","end":"2026-06-24T18:00:46.43737Z","steps":["trace[652800771] 'agreement among raft nodes before linearized reading' (duration: 152.949602ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:47.686538Z","caller":"traceutil/trace.go:171","msg":"trace[1404955179] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"483.257584ms","start":"2026-06-24T18:00:47.203249Z","end":"2026-06-24T18:00:47.686507Z","steps":["trace[1404955179] 'read index received' (duration: 482.982207ms)","trace[1404955179] 'applied index is now lower than readState.Index' (duration: 274.357µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:00:47.686698Z","caller":"traceutil/trace.go:171","msg":"trace[885629047] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"543.490859ms","start":"2026-06-24T18:00:47.143167Z","end":"2026-06-24T18:00:47.686658Z","steps":["trace[885629047] 'process raft request' (duration: 543.154891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:47.686854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.574011ms","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-06-24T18:00:47.686891Z","caller":"traceutil/trace.go:171","msg":"trace[2034960182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"483.643603ms","start":"2026-06-24T18:00:47.203236Z","end":"2026-06-24T18:00:47.686879Z","steps":["trace[2034960182] 'agreement among raft nodes before linearized reading' (duration: 483.426817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:47.686926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:47.203148Z","time spent":"483.771226ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T18:00:47.686936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.424183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-24T18:00:47.686974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:47.143131Z","time spent":"543.698354ms","remote":"127.0.0.1:44718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:47.686997Z","caller":"traceutil/trace.go:171","msg":"trace[1620094085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"427.561196ms","start":"2026-06-24T18:00:47.25942Z","end":"2026-06-24T18:00:47.686981Z","steps":["trace[1620094085] 'agreement among raft nodes before linearized reading' (duration: 427.30002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:47.687133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.079509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:00:47.687171Z","caller":"traceutil/trace.go:171","msg":"trace[1689190351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"403.14313ms","start":"2026-06-24T18:00:47.284016Z","end":"2026-06-24T18:00:47.687159Z","steps":["trace[1689190351] 'agreement among raft nodes before linearized reading' (duration: 403.080639ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:47.687193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:47.284002Z","time spent":"403.184171ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:00:47.687252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.801964ms","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-06-24T18:00:47.687307Z","caller":"traceutil/trace.go:171","msg":"trace[1802827471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"425.869695ms","start":"2026-06-24T18:00:47.261421Z","end":"2026-06-24T18:00:47.68729Z","steps":["trace[1802827471] 'agreement among raft nodes before linearized reading' (duration: 425.691781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:47.68734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:47.26141Z","time spent":"425.922226ms","remote":"127.0.0.1:44804","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-06-24T18:00:47.687075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.938843ms","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-06-24T18:00:47.687478Z","caller":"traceutil/trace.go:171","msg":"trace[129115393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"425.390334ms","start":"2026-06-24T18:00:47.262079Z","end":"2026-06-24T18:00:47.687469Z","steps":["trace[129115393] 'agreement among raft nodes before linearized reading' (duration: 424.923093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:47.687499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:47.262056Z","time spent":"425.437055ms","remote":"127.0.0.1:44804","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-06-24T18:00:47.68708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:47.259388Z","time spent":"427.681329ms","remote":"127.0.0.1:44804","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-06-24T18:00:48.506316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.363777ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T18:00:49.87059Z","caller":"traceutil/trace.go:171","msg":"trace[645628604] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"146.422066ms","start":"2026-06-24T18:00:49.724141Z","end":"2026-06-24T18:00:49.870563Z","steps":["trace[645628604] 'process raft request' (duration: 146.252362ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:49.871598Z","caller":"traceutil/trace.go:171","msg":"trace[997340290] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:639; }","duration":"115.559054ms","start":"2026-06-24T18:00:49.756008Z","end":"2026-06-24T18:00:49.871567Z","steps":["trace[997340290] 'read index received' (duration: 114.409566ms)","trace[997340290] 'applied index is now lower than readState.Index' (duration: 1.148318ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:49.871977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.949664ms","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-06-24T18:00:49.872013Z","caller":"traceutil/trace.go:171","msg":"trace[1637955041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"116.070576ms","start":"2026-06-24T18:00:49.755933Z","end":"2026-06-24T18:00:49.872004Z","steps":["trace[1637955041] 'agreement among raft nodes before linearized reading' (duration: 115.923203ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:49.8721Z","caller":"traceutil/trace.go:171","msg":"trace[774263316] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"146.905787ms","start":"2026-06-24T18:00:49.725186Z","end":"2026-06-24T18:00:49.872091Z","steps":["trace[774263316] 'process raft request' (duration: 146.234991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:49.872264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.407861ms","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-06-24T18:00:49.872373Z","caller":"traceutil/trace.go:171","msg":"trace[532861419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"115.583655ms","start":"2026-06-24T18:00:49.756773Z","end":"2026-06-24T18:00:49.872357Z","steps":["trace[532861419] 'agreement among raft nodes before linearized reading' (duration: 115.208166ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:50.232737Z","caller":"traceutil/trace.go:171","msg":"trace[1537902389] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"354.044461ms","start":"2026-06-24T18:00:49.87866Z","end":"2026-06-24T18:00:50.232705Z","steps":["trace[1537902389] 'process raft request' (duration: 347.082104ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:50.232906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:49.87864Z","time spent":"354.188224ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:50.233993Z","caller":"traceutil/trace.go:171","msg":"trace[1638440946] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"354.085251ms","start":"2026-06-24T18:00:49.879881Z","end":"2026-06-24T18:00:50.233966Z","steps":["trace[1638440946] 'process raft request' (duration: 353.909967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:00:50.234212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:00:49.879874Z","time spent":"354.222585ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:00:58.012Z","caller":"traceutil/trace.go:171","msg":"trace[1087148270] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"100.75308ms","start":"2026-06-24T18:00:57.911221Z","end":"2026-06-24T18:00:58.011974Z","steps":["trace[1087148270] 'process raft request' (duration: 100.615637ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:58.050356Z","caller":"traceutil/trace.go:171","msg":"trace[901466375] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"137.297108ms","start":"2026-06-24T18:00:57.913027Z","end":"2026-06-24T18:00:58.050324Z","steps":["trace[901466375] 'process raft request' (duration: 137.133124ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:58.493675Z","caller":"traceutil/trace.go:171","msg":"trace[72851902] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"101.718322ms","start":"2026-06-24T18:00:58.391929Z","end":"2026-06-24T18:00:58.493648Z","steps":["trace[72851902] 'read index received' (duration: 101.537898ms)","trace[72851902] 'applied index is now lower than readState.Index' (duration: 178.924µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:00:58.49409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.128992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:1492"} {"level":"info","ts":"2026-06-24T18:00:58.493776Z","caller":"traceutil/trace.go:171","msg":"trace[1881049576] transaction","detail":"{read_only:false; number_of_response:0; response_revision:713; }","duration":"135.562435ms","start":"2026-06-24T18:00:58.358162Z","end":"2026-06-24T18:00:58.493724Z","steps":["trace[1881049576] 'process raft request' (duration: 135.392151ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:58.494198Z","caller":"traceutil/trace.go:171","msg":"trace[1625935365] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:713; }","duration":"102.295566ms","start":"2026-06-24T18:00:58.391887Z","end":"2026-06-24T18:00:58.494183Z","steps":["trace[1625935365] 'agreement among raft nodes before linearized reading' (duration: 102.015259ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:00:58.684077Z","caller":"traceutil/trace.go:171","msg":"trace[990318923] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"132.927751ms","start":"2026-06-24T18:00:58.551123Z","end":"2026-06-24T18:00:58.684051Z","steps":["trace[990318923] 'process raft request' (duration: 131.871705ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:05.736569Z","caller":"traceutil/trace.go:171","msg":"trace[1422951832] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"454.963174ms","start":"2026-06-24T18:01:05.281587Z","end":"2026-06-24T18:01:05.73655Z","steps":["trace[1422951832] 'read index received' (duration: 454.77384ms)","trace[1422951832] 'applied index is now lower than readState.Index' (duration: 188.774µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:01:05.736638Z","caller":"traceutil/trace.go:171","msg":"trace[1375726237] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"495.142709ms","start":"2026-06-24T18:01:05.241465Z","end":"2026-06-24T18:01:05.736608Z","steps":["trace[1375726237] 'process raft request' (duration: 494.956684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:05.736805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:05.241445Z","time spent":"495.272071ms","remote":"127.0.0.1:44718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:01:05.736993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.118968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:01:05.737115Z","caller":"traceutil/trace.go:171","msg":"trace[1318818992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"455.533298ms","start":"2026-06-24T18:01:05.281565Z","end":"2026-06-24T18:01:05.737098Z","steps":["trace[1318818992] 'agreement among raft nodes before linearized reading' (duration: 455.089367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:05.737181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:05.281556Z","time spent":"455.613639ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:01:28.787229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.419874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:01:28.787332Z","caller":"traceutil/trace.go:171","msg":"trace[485820077] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:937; }","duration":"102.554107ms","start":"2026-06-24T18:01:28.684759Z","end":"2026-06-24T18:01:28.787313Z","steps":["trace[485820077] 'count revisions from in-memory index tree' (duration: 102.25334ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:29.043388Z","caller":"traceutil/trace.go:171","msg":"trace[426023068] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"249.167994ms","start":"2026-06-24T18:01:28.794175Z","end":"2026-06-24T18:01:29.043343Z","steps":["trace[426023068] 'process raft request' (duration: 248.918948ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:40.575403Z","caller":"traceutil/trace.go:171","msg":"trace[1260208316] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"206.937767ms","start":"2026-06-24T18:01:40.368445Z","end":"2026-06-24T18:01:40.575383Z","steps":["trace[1260208316] 'read index received' (duration: 206.721612ms)","trace[1260208316] 'applied index is now lower than readState.Index' (duration: 215.495µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:01:40.575481Z","caller":"traceutil/trace.go:171","msg":"trace[1591454811] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"208.212797ms","start":"2026-06-24T18:01:40.367218Z","end":"2026-06-24T18:01:40.575431Z","steps":["trace[1591454811] 'process raft request' (duration: 208.025493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:40.575596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.151832ms","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-06-24T18:01:40.575625Z","caller":"traceutil/trace.go:171","msg":"trace[17873830] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:976; }","duration":"207.227013ms","start":"2026-06-24T18:01:40.36839Z","end":"2026-06-24T18:01:40.575617Z","steps":["trace[17873830] 'agreement among raft nodes before linearized reading' (duration: 207.09194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:41.177111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.966366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:01:41.177391Z","caller":"traceutil/trace.go:171","msg":"trace[592108086] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1018; }","duration":"544.999821ms","start":"2026-06-24T18:01:40.632376Z","end":"2026-06-24T18:01:41.177376Z","steps":["trace[592108086] 'read index received' (duration: 187.674437ms)","trace[592108086] 'applied index is now lower than readState.Index' (duration: 357.324264ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:01:41.177577Z","caller":"traceutil/trace.go:171","msg":"trace[1534174897] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"597.698319ms","start":"2026-06-24T18:01:40.579869Z","end":"2026-06-24T18:01:41.177568Z","steps":["trace[1534174897] 'process raft request' (duration: 240.19653ms)","trace[1534174897] 'compare' (duration: 356.831413ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:01:41.177631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:40.57984Z","time spent":"597.765881ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:01:41.177781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.388691ms","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-06-24T18:01:41.177868Z","caller":"traceutil/trace.go:171","msg":"trace[595936562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"545.512623ms","start":"2026-06-24T18:01:40.632334Z","end":"2026-06-24T18:01:41.177846Z","steps":["trace[595936562] 'agreement among raft nodes before linearized reading' (duration: 545.265608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:41.177911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:40.632315Z","time spent":"545.588016ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T18:01:41.178082Z","caller":"traceutil/trace.go:171","msg":"trace[429745648] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"597.652938ms","start":"2026-06-24T18:01:40.580379Z","end":"2026-06-24T18:01:41.178032Z","steps":["trace[429745648] 'process raft request' (duration: 596.89024ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:01:41.178112Z","caller":"traceutil/trace.go:171","msg":"trace[1447218551] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"537.028851ms","start":"2026-06-24T18:01:40.641067Z","end":"2026-06-24T18:01:41.178096Z","steps":["trace[1447218551] 'process raft request' (duration: 536.279543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:41.178261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:40.580354Z","time spent":"597.812002ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:01:41.178305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:40.641033Z","time spent":"537.208236ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:01:41.726973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.495118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:01:41.727085Z","caller":"traceutil/trace.go:171","msg":"trace[64500144] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"544.760286ms","start":"2026-06-24T18:01:41.182304Z","end":"2026-06-24T18:01:41.727064Z","steps":["trace[64500144] 'process raft request' (duration: 418.087474ms)","trace[64500144] 'compare' (duration: 126.372725ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:01:41.727171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:41.182286Z","time spent":"544.839899ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:01:41.728831Z","caller":"traceutil/trace.go:171","msg":"trace[1040486427] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"443.144132ms","start":"2026-06-24T18:01:41.285672Z","end":"2026-06-24T18:01:41.728816Z","steps":["trace[1040486427] 'read index received' (duration: 314.734718ms)","trace[1040486427] 'applied index is now lower than readState.Index' (duration: 128.408444ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:01:41.728928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.259534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:01:41.728963Z","caller":"traceutil/trace.go:171","msg":"trace[2043254523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"443.340706ms","start":"2026-06-24T18:01:41.285613Z","end":"2026-06-24T18:01:41.728954Z","steps":["trace[2043254523] 'agreement among raft nodes before linearized reading' (duration: 443.263664ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:41.728994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:41.285593Z","time spent":"443.395148ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T18:01:41.729045Z","caller":"traceutil/trace.go:171","msg":"trace[75358302] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"545.945985ms","start":"2026-06-24T18:01:41.183082Z","end":"2026-06-24T18:01:41.729028Z","steps":["trace[75358302] 'process raft request' (duration: 545.663858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:01:41.729227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:01:41.183044Z","time spent":"546.135049ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:06.405118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103972705700662,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T18:02:06.734675Z","caller":"traceutil/trace.go:171","msg":"trace[1514635440] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"829.816147ms","start":"2026-06-24T18:02:05.904836Z","end":"2026-06-24T18:02:06.734652Z","steps":["trace[1514635440] 'read index received' (duration: 829.723484ms)","trace[1514635440] 'applied index is now lower than readState.Index' (duration: 91.433µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:06.734737Z","caller":"traceutil/trace.go:171","msg":"trace[1227436602] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"831.707752ms","start":"2026-06-24T18:02:05.903003Z","end":"2026-06-24T18:02:06.734711Z","steps":["trace[1227436602] 'process raft request' (duration: 831.512357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:06.734866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:05.902986Z","time spent":"831.813014ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:06.734873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.207254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:06.734927Z","caller":"traceutil/trace.go:171","msg":"trace[292289049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"451.293056ms","start":"2026-06-24T18:02:06.28362Z","end":"2026-06-24T18:02:06.734913Z","steps":["trace[292289049] 'agreement among raft nodes before linearized reading' (duration: 451.196274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:06.734945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.080273ms","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-06-24T18:02:06.735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:06.283605Z","time spent":"451.388079ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T18:02:06.735011Z","caller":"traceutil/trace.go:171","msg":"trace[1517438570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"830.178686ms","start":"2026-06-24T18:02:05.904818Z","end":"2026-06-24T18:02:06.734997Z","steps":["trace[1517438570] 'agreement among raft nodes before linearized reading' (duration: 829.957011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:06.735007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.180743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-24T18:02:06.735059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:05.904811Z","time spent":"830.236157ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T18:02:06.735105Z","caller":"traceutil/trace.go:171","msg":"trace[1994664513] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1060; }","duration":"106.337237ms","start":"2026-06-24T18:02:06.628746Z","end":"2026-06-24T18:02:06.735083Z","steps":["trace[1994664513] 'agreement among raft nodes before linearized reading' (duration: 106.167373ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:07.223514Z","caller":"traceutil/trace.go:171","msg":"trace[1828437693] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"483.549176ms","start":"2026-06-24T18:02:06.739917Z","end":"2026-06-24T18:02:07.223466Z","steps":["trace[1828437693] 'process raft request' (duration: 411.277112ms)","trace[1828437693] 'compare' (duration: 71.857695ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:07.223669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:06.739901Z","time spent":"483.689839ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:07.471725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.190037ms","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-06-24T18:02:07.471745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.664572ms","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-06-24T18:02:07.471758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.880036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:02:07.471804Z","caller":"traceutil/trace.go:171","msg":"trace[1078373358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"120.306351ms","start":"2026-06-24T18:02:07.35148Z","end":"2026-06-24T18:02:07.471786Z","steps":["trace[1078373358] 'range keys from in-memory index tree' (duration: 120.036904ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:07.471812Z","caller":"traceutil/trace.go:171","msg":"trace[879918197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"126.786285ms","start":"2026-06-24T18:02:07.34501Z","end":"2026-06-24T18:02:07.471797Z","steps":["trace[879918197] 'range keys from in-memory index tree' (duration: 126.499448ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:07.47184Z","caller":"traceutil/trace.go:171","msg":"trace[1842328276] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1063; }","duration":"111.061309ms","start":"2026-06-24T18:02:07.360759Z","end":"2026-06-24T18:02:07.47182Z","steps":["trace[1842328276] 'count revisions from in-memory index tree' (duration: 110.768802ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:07.58775Z","caller":"traceutil/trace.go:171","msg":"trace[137498345] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"112.205957ms","start":"2026-06-24T18:02:07.475512Z","end":"2026-06-24T18:02:07.587718Z","steps":["trace[137498345] 'process raft request' (duration: 112.089285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:07.8258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.118302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:02:07.825917Z","caller":"traceutil/trace.go:171","msg":"trace[1761536212] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"349.924168ms","start":"2026-06-24T18:02:07.475973Z","end":"2026-06-24T18:02:07.825897Z","steps":["trace[1761536212] 'process raft request' (duration: 204.568951ms)","trace[1761536212] 'compare' (duration: 145.023089ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:07.825984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:07.475945Z","time spent":"350.00444ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:08.993825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.697225ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T18:02:08.993955Z","caller":"traceutil/trace.go:171","msg":"trace[1725637711] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1113; }","duration":"707.013637ms","start":"2026-06-24T18:02:08.286919Z","end":"2026-06-24T18:02:08.993932Z","steps":["trace[1725637711] 'read index received' (duration: 120.572µs)","trace[1725637711] 'applied index is now lower than readState.Index' (duration: 706.891505ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:08.994142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.209652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:02:08.994157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.337701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:08.994183Z","caller":"traceutil/trace.go:171","msg":"trace[713272497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"707.287653ms","start":"2026-06-24T18:02:08.286881Z","end":"2026-06-24T18:02:08.994169Z","steps":["trace[713272497] 'agreement among raft nodes before linearized reading' (duration: 707.15321ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:08.994221Z","caller":"traceutil/trace.go:171","msg":"trace[2087740138] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1066; }","duration":"408.450484ms","start":"2026-06-24T18:02:08.585751Z","end":"2026-06-24T18:02:08.994202Z","steps":["trace[2087740138] 'agreement among raft nodes before linearized reading' (duration: 408.338011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:08.99423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:08.286851Z","time spent":"707.367285ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:02:08.994232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.262341ms","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-06-24T18:02:08.994233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.30928ms","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-06-24T18:02:08.994293Z","caller":"traceutil/trace.go:171","msg":"trace[13578512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"245.414145ms","start":"2026-06-24T18:02:08.748863Z","end":"2026-06-24T18:02:08.994277Z","steps":["trace[13578512] 'agreement among raft nodes before linearized reading' (duration: 245.23056ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:08.994302Z","caller":"traceutil/trace.go:171","msg":"trace[1856208858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"255.422193ms","start":"2026-06-24T18:02:08.738864Z","end":"2026-06-24T18:02:08.994286Z","steps":["trace[1856208858] 'agreement among raft nodes before linearized reading' (duration: 255.253169ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:08.994268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:08.585732Z","time spent":"408.521576ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-06-24T18:02:10.067084Z","caller":"traceutil/trace.go:171","msg":"trace[1452321605] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"232.355923ms","start":"2026-06-24T18:02:09.834693Z","end":"2026-06-24T18:02:10.067049Z","steps":["trace[1452321605] 'process raft request' (duration: 232.147588ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:25.021437Z","caller":"traceutil/trace.go:171","msg":"trace[1225985263] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"107.582116ms","start":"2026-06-24T18:02:24.913809Z","end":"2026-06-24T18:02:25.021391Z","steps":["trace[1225985263] 'process raft request' (duration: 61.944257ms)","trace[1225985263] 'compare' (duration: 45.28717ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:25.262822Z","caller":"traceutil/trace.go:171","msg":"trace[1732984263] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"230.5931ms","start":"2026-06-24T18:02:25.032179Z","end":"2026-06-24T18:02:25.262772Z","steps":["trace[1732984263] 'process raft request' (duration: 154.612808ms)","trace[1732984263] 'compare' (duration: 75.790138ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:25.262848Z","caller":"traceutil/trace.go:171","msg":"trace[407852548] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"210.533642ms","start":"2026-06-24T18:02:25.052272Z","end":"2026-06-24T18:02:25.262806Z","steps":["trace[407852548] 'read index received' (duration: 134.53829ms)","trace[407852548] 'applied index is now lower than readState.Index' (duration: 75.994052ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:25.263227Z","caller":"traceutil/trace.go:171","msg":"trace[2033470183] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"208.660948ms","start":"2026-06-24T18:02:25.054554Z","end":"2026-06-24T18:02:25.263215Z","steps":["trace[2033470183] 'process raft request' (duration: 208.203057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:25.263284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.987784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"warn","ts":"2026-06-24T18:02:25.263318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.555393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-06-24T18:02:25.263344Z","caller":"traceutil/trace.go:171","msg":"trace[1735492841] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1166; }","duration":"211.081386ms","start":"2026-06-24T18:02:25.05224Z","end":"2026-06-24T18:02:25.263321Z","steps":["trace[1735492841] 'agreement among raft nodes before linearized reading' (duration: 210.645535ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:25.263376Z","caller":"traceutil/trace.go:171","msg":"trace[1322541025] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1167; }","duration":"210.633985ms","start":"2026-06-24T18:02:25.052726Z","end":"2026-06-24T18:02:25.26336Z","steps":["trace[1322541025] 'agreement among raft nodes before linearized reading' (duration: 210.454031ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:27.570197Z","caller":"traceutil/trace.go:171","msg":"trace[1277202702] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"286.55365ms","start":"2026-06-24T18:02:27.283616Z","end":"2026-06-24T18:02:27.57017Z","steps":["trace[1277202702] 'process raft request' (duration: 190.019515ms)","trace[1277202702] 'compare' (duration: 96.35686ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:30.075393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103972705700945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T18:02:30.456312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.10895377s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T18:02:30.456734Z","caller":"traceutil/trace.go:171","msg":"trace[2103537259] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"1.109474362s","start":"2026-06-24T18:02:29.347235Z","end":"2026-06-24T18:02:30.456709Z","steps":["trace[2103537259] 'process raft request' (duration: 1.109308348s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:30.456896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:29.347207Z","time spent":"1.109605906s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:31.3533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.71251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:02:31.353394Z","caller":"traceutil/trace.go:171","msg":"trace[2132176128] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1238; }","duration":"1.779058745s","start":"2026-06-24T18:02:29.57432Z","end":"2026-06-24T18:02:31.353379Z","steps":["trace[2132176128] 'read index received' (duration: 882.225642ms)","trace[2132176128] 'applied index is now lower than readState.Index' (duration: 896.831723ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:31.353475Z","caller":"traceutil/trace.go:171","msg":"trace[676102134] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"1.7771829s","start":"2026-06-24T18:02:29.576281Z","end":"2026-06-24T18:02:31.353464Z","steps":["trace[676102134] 'process raft request' (duration: 1.172209603s)","trace[676102134] 'compare' (duration: 604.441584ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:31.353547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:29.576255Z","time spent":"1.777262812s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:31.353821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779498286s","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-06-24T18:02:31.353855Z","caller":"traceutil/trace.go:171","msg":"trace[1468673487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1186; }","duration":"1.779564027s","start":"2026-06-24T18:02:29.574282Z","end":"2026-06-24T18:02:31.353846Z","steps":["trace[1468673487] 'agreement among raft nodes before linearized reading' (duration: 1.779429074s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:31.353887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:29.574262Z","time spent":"1.779614419s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T18:02:31.354057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071645742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:31.354144Z","caller":"traceutil/trace.go:171","msg":"trace[750499719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1186; }","duration":"1.071777016s","start":"2026-06-24T18:02:30.28235Z","end":"2026-06-24T18:02:31.354127Z","steps":["trace[750499719] 'agreement among raft nodes before linearized reading' (duration: 1.071602061s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:31.354187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069335837s","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-06-24T18:02:31.354196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:30.28233Z","time spent":"1.071857948s","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T18:02:31.354226Z","caller":"traceutil/trace.go:171","msg":"trace[1776507665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1186; }","duration":"1.069374998s","start":"2026-06-24T18:02:30.284842Z","end":"2026-06-24T18:02:31.354217Z","steps":["trace[1776507665] 'agreement among raft nodes before linearized reading' (duration: 1.069284746s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:31.354258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:30.28482Z","time spent":"1.06943214s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T18:02:31.354589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627521957s","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-06-24T18:02:31.354641Z","caller":"traceutil/trace.go:171","msg":"trace[258287308] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1186; }","duration":"1.627612099s","start":"2026-06-24T18:02:29.727022Z","end":"2026-06-24T18:02:31.354634Z","steps":["trace[258287308] 'agreement among raft nodes before linearized reading' (duration: 1.627072296s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:31.354681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:29.727Z","time spent":"1.62767273s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T18:02:31.353978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.77512287s","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-06-24T18:02:31.354931Z","caller":"traceutil/trace.go:171","msg":"trace[1341457451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1186; }","duration":"1.776105544s","start":"2026-06-24T18:02:29.578816Z","end":"2026-06-24T18:02:31.354922Z","steps":["trace[1341457451] 'agreement among raft nodes before linearized reading' (duration: 1.77510009s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:31.354957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:29.5788Z","time spent":"1.776148844s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-24T18:02:44.85898Z","caller":"traceutil/trace.go:171","msg":"trace[1858803670] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"206.222305ms","start":"2026-06-24T18:02:44.652729Z","end":"2026-06-24T18:02:44.858951Z","steps":["trace[1858803670] 'read index received' (duration: 206.057561ms)","trace[1858803670] 'applied index is now lower than readState.Index' (duration: 163.264µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:44.859043Z","caller":"traceutil/trace.go:171","msg":"trace[1251702952] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"265.937714ms","start":"2026-06-24T18:02:44.593077Z","end":"2026-06-24T18:02:44.859015Z","steps":["trace[1251702952] 'process raft request' (duration: 265.700148ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:44.859141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.377239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:44.859193Z","caller":"traceutil/trace.go:171","msg":"trace[1740732074] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1231; }","duration":"206.493832ms","start":"2026-06-24T18:02:44.652687Z","end":"2026-06-24T18:02:44.859181Z","steps":["trace[1740732074] 'agreement among raft nodes before linearized reading' (duration: 206.383749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.01161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322103972705701068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T18:02:46.321358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03788997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:46.321416Z","caller":"traceutil/trace.go:171","msg":"trace[1564412787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1231; }","duration":"1.037986102s","start":"2026-06-24T18:02:45.283417Z","end":"2026-06-24T18:02:46.321403Z","steps":["trace[1564412787] 'range keys from in-memory index tree' (duration: 1.037820708s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.321444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.283398Z","time spent":"1.038040943s","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:02:46.321669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.18265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:02:46.321703Z","caller":"traceutil/trace.go:171","msg":"trace[633150597] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"909.309253ms","start":"2026-06-24T18:02:45.412387Z","end":"2026-06-24T18:02:46.321697Z","steps":["trace[633150597] 'process raft request' (duration: 748.052691ms)","trace[633150597] 'compare' (duration: 160.577346ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:46.321729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.412355Z","time spent":"909.360115ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T18:02:46.717253Z","caller":"traceutil/trace.go:171","msg":"trace[324779677] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1288; }","duration":"1.206576082s","start":"2026-06-24T18:02:45.510652Z","end":"2026-06-24T18:02:46.717228Z","steps":["trace[324779677] 'read index received' (duration: 649.782576ms)","trace[324779677] 'applied index is now lower than readState.Index' (duration: 556.792456ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:46.717506Z","caller":"traceutil/trace.go:171","msg":"trace[125483171] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"874.179668ms","start":"2026-06-24T18:02:45.843301Z","end":"2026-06-24T18:02:46.717481Z","steps":["trace[125483171] 'process raft request' (duration: 801.241052ms)","trace[125483171] 'compare' (duration: 72.535616ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:46.717552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.641776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:46.717592Z","caller":"traceutil/trace.go:171","msg":"trace[1413573278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"395.733439ms","start":"2026-06-24T18:02:46.321846Z","end":"2026-06-24T18:02:46.71758Z","steps":["trace[1413573278] 'agreement among raft nodes before linearized reading' (duration: 395.659537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.717632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:46.321823Z","time spent":"395.801391ms","remote":"127.0.0.1:44566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:02:46.717648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.843274Z","time spent":"874.298821ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:46.717686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023300229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:46.717731Z","caller":"traceutil/trace.go:171","msg":"trace[1071889114] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1233; }","duration":"1.023399481s","start":"2026-06-24T18:02:45.694318Z","end":"2026-06-24T18:02:46.717717Z","steps":["trace[1071889114] 'agreement among raft nodes before linearized reading' (duration: 1.023338339s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.717772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.694293Z","time spent":"1.023469973s","remote":"127.0.0.1:38428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T18:02:46.717826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.860147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:46.717861Z","caller":"traceutil/trace.go:171","msg":"trace[400245476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"394.934839ms","start":"2026-06-24T18:02:46.322918Z","end":"2026-06-24T18:02:46.717852Z","steps":["trace[400245476] 'agreement among raft nodes before linearized reading' (duration: 394.887768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.717627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190549706s","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-06-24T18:02:46.717891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:46.322897Z","time spent":"394.987091ms","remote":"127.0.0.1:44554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T18:02:46.717958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207247638s","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-06-24T18:02:46.718036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150174103s","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-06-24T18:02:46.718041Z","caller":"traceutil/trace.go:171","msg":"trace[1746653789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1233; }","duration":"1.207426412s","start":"2026-06-24T18:02:45.510597Z","end":"2026-06-24T18:02:46.718023Z","steps":["trace[1746653789] 'agreement among raft nodes before linearized reading' (duration: 1.207176226s)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:46.718077Z","caller":"traceutil/trace.go:171","msg":"trace[566860824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1233; }","duration":"1.150233825s","start":"2026-06-24T18:02:45.567833Z","end":"2026-06-24T18:02:46.718067Z","steps":["trace[566860824] 'agreement among raft nodes before linearized reading' (duration: 1.150134283s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.718095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.510571Z","time spent":"1.207511985s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-24T18:02:46.718109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190894394s","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-06-24T18:02:46.718112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.567822Z","time spent":"1.150283816s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-24T18:02:46.718133Z","caller":"traceutil/trace.go:171","msg":"trace[694960778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1233; }","duration":"1.190944916s","start":"2026-06-24T18:02:45.527182Z","end":"2026-06-24T18:02:46.718127Z","steps":["trace[694960778] 'agreement among raft nodes before linearized reading' (duration: 1.190875034s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.718161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.527165Z","time spent":"1.190990616s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T18:02:46.71793Z","caller":"traceutil/trace.go:171","msg":"trace[571391557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"1.190876863s","start":"2026-06-24T18:02:45.527026Z","end":"2026-06-24T18:02:46.717903Z","steps":["trace[571391557] 'agreement among raft nodes before linearized reading' (duration: 1.190455393s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:46.71819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:45.527004Z","time spent":"1.191181271s","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T18:02:46.890827Z","caller":"traceutil/trace.go:171","msg":"trace[2054204249] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"168.904527ms","start":"2026-06-24T18:02:46.721897Z","end":"2026-06-24T18:02:46.890801Z","steps":["trace[2054204249] 'process raft request' (duration: 110.07121ms)","trace[2054204249] 'compare' (duration: 58.504249ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:46.890956Z","caller":"traceutil/trace.go:171","msg":"trace[1071042737] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"168.925538ms","start":"2026-06-24T18:02:46.722018Z","end":"2026-06-24T18:02:46.890943Z","steps":["trace[1071042737] 'process raft request' (duration: 168.693132ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:46.89135Z","caller":"traceutil/trace.go:171","msg":"trace[283509654] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"169.285166ms","start":"2026-06-24T18:02:46.722038Z","end":"2026-06-24T18:02:46.891323Z","steps":["trace[283509654] 'process raft request' (duration: 168.843305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:48.499089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.923423ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T18:02:48.499627Z","caller":"traceutil/trace.go:171","msg":"trace[1421570910] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1295; }","duration":"215.213482ms","start":"2026-06-24T18:02:48.284391Z","end":"2026-06-24T18:02:48.499605Z","steps":["trace[1421570910] 'read index received' (duration: 44.311µs)","trace[1421570910] 'applied index is now lower than readState.Index' (duration: 215.166821ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:48.499776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.366925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:48.499815Z","caller":"traceutil/trace.go:171","msg":"trace[222061686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"215.453888ms","start":"2026-06-24T18:02:48.284351Z","end":"2026-06-24T18:02:48.499805Z","steps":["trace[222061686] 'agreement among raft nodes before linearized reading' (duration: 215.366306ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.510812Z","caller":"traceutil/trace.go:171","msg":"trace[1955837276] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"184.154134ms","start":"2026-06-24T18:02:48.326636Z","end":"2026-06-24T18:02:48.51079Z","steps":["trace[1955837276] 'process raft request' (duration: 183.97541ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:48.546359Z","caller":"traceutil/trace.go:171","msg":"trace[211750753] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"186.48879ms","start":"2026-06-24T18:02:48.359818Z","end":"2026-06-24T18:02:48.546306Z","steps":["trace[211750753] 'process raft request' (duration: 186.297086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:48.546389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.614215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:48.546485Z","caller":"traceutil/trace.go:171","msg":"trace[1862131343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1240; }","duration":"135.726218ms","start":"2026-06-24T18:02:48.410729Z","end":"2026-06-24T18:02:48.546456Z","steps":["trace[1862131343] 'agreement among raft nodes before linearized reading' (duration: 135.580514ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:50.729334Z","caller":"traceutil/trace.go:171","msg":"trace[1738852301] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"213.374228ms","start":"2026-06-24T18:02:50.51594Z","end":"2026-06-24T18:02:50.729314Z","steps":["trace[1738852301] 'process raft request' (duration: 213.248905ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:51.204481Z","caller":"traceutil/trace.go:171","msg":"trace[895354171] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"241.484663ms","start":"2026-06-24T18:02:50.962969Z","end":"2026-06-24T18:02:51.204454Z","steps":["trace[895354171] 'process raft request' (duration: 241.312079ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:51.221897Z","caller":"traceutil/trace.go:171","msg":"trace[2003476128] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1308; }","duration":"212.316112ms","start":"2026-06-24T18:02:51.009562Z","end":"2026-06-24T18:02:51.221878Z","steps":["trace[2003476128] 'read index received' (duration: 194.705678ms)","trace[2003476128] 'applied index is now lower than readState.Index' (duration: 17.609234ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:51.221914Z","caller":"traceutil/trace.go:171","msg":"trace[465697285] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"258.493673ms","start":"2026-06-24T18:02:50.963385Z","end":"2026-06-24T18:02:51.221878Z","steps":["trace[465697285] 'process raft request' (duration: 258.187515ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:51.22195Z","caller":"traceutil/trace.go:171","msg":"trace[848646878] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"256.109536ms","start":"2026-06-24T18:02:50.965808Z","end":"2026-06-24T18:02:51.221917Z","steps":["trace[848646878] 'process raft request' (duration: 255.959742ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:51.222341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.738732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-06-24T18:02:51.222496Z","caller":"traceutil/trace.go:171","msg":"trace[411801444] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1253; }","duration":"212.832415ms","start":"2026-06-24T18:02:51.009547Z","end":"2026-06-24T18:02:51.22238Z","steps":["trace[411801444] 'agreement among raft nodes before linearized reading' (duration: 212.433765ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:52.891332Z","caller":"traceutil/trace.go:171","msg":"trace[1467113804] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"130.450041ms","start":"2026-06-24T18:02:52.760856Z","end":"2026-06-24T18:02:52.891306Z","steps":["trace[1467113804] 'read index received' (duration: 130.291577ms)","trace[1467113804] 'applied index is now lower than readState.Index' (duration: 157.354µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:52.891366Z","caller":"traceutil/trace.go:171","msg":"trace[1332110989] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"153.091916ms","start":"2026-06-24T18:02:52.738242Z","end":"2026-06-24T18:02:52.891334Z","steps":["trace[1332110989] 'process raft request' (duration: 152.891261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:52.891639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.764039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-24T18:02:52.891705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.704209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:52.891727Z","caller":"traceutil/trace.go:171","msg":"trace[838027922] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:1256; }","duration":"130.899842ms","start":"2026-06-24T18:02:52.760814Z","end":"2026-06-24T18:02:52.891714Z","steps":["trace[838027922] 'agreement among raft nodes before linearized reading' (duration: 130.652516ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:52.891892Z","caller":"traceutil/trace.go:171","msg":"trace[1516291204] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1256; }","duration":"106.968786ms","start":"2026-06-24T18:02:52.784774Z","end":"2026-06-24T18:02:52.891743Z","steps":["trace[1516291204] 'agreement among raft nodes before linearized reading' (duration: 106.689849ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:57.010945Z","caller":"traceutil/trace.go:171","msg":"trace[1675154181] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"233.617355ms","start":"2026-06-24T18:02:56.777304Z","end":"2026-06-24T18:02:57.010921Z","steps":["trace[1675154181] 'read index received' (duration: 233.474232ms)","trace[1675154181] 'applied index is now lower than readState.Index' (duration: 135.623µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:57.011129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.789639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:02:57.011111Z","caller":"traceutil/trace.go:171","msg":"trace[652642707] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"264.600861ms","start":"2026-06-24T18:02:56.746385Z","end":"2026-06-24T18:02:57.010986Z","steps":["trace[652642707] 'process raft request' (duration: 264.366255ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:57.011223Z","caller":"traceutil/trace.go:171","msg":"trace[1617694766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1292; }","duration":"233.931573ms","start":"2026-06-24T18:02:56.777265Z","end":"2026-06-24T18:02:57.011196Z","steps":["trace[1617694766] 'agreement among raft nodes before linearized reading' (duration: 233.747088ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:57.337022Z","caller":"traceutil/trace.go:171","msg":"trace[917338647] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"325.904937ms","start":"2026-06-24T18:02:57.011091Z","end":"2026-06-24T18:02:57.336996Z","steps":["trace[917338647] 'read index received' (duration: 325.535108ms)","trace[917338647] 'applied index is now lower than readState.Index' (duration: 368.979µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T18:02:57.33732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.64808ms","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-06-24T18:02:57.33741Z","caller":"traceutil/trace.go:171","msg":"trace[2033517706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"347.789834ms","start":"2026-06-24T18:02:56.989602Z","end":"2026-06-24T18:02:57.337392Z","steps":["trace[2033517706] 'agreement among raft nodes before linearized reading' (duration: 347.561558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:57.337459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:56.989577Z","time spent":"347.873566ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T18:02:57.337451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.091331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-24T18:02:57.337504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.12371ms","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-06-24T18:02:57.337537Z","caller":"traceutil/trace.go:171","msg":"trace[1105914053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"110.272495ms","start":"2026-06-24T18:02:57.227249Z","end":"2026-06-24T18:02:57.337522Z","steps":["trace[1105914053] 'agreement among raft nodes before linearized reading' (duration: 109.925507ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:57.337584Z","caller":"traceutil/trace.go:171","msg":"trace[902137425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"345.281664ms","start":"2026-06-24T18:02:56.992288Z","end":"2026-06-24T18:02:57.337569Z","steps":["trace[902137425] 'agreement among raft nodes before linearized reading' (duration: 344.812523ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:02:57.337114Z","caller":"traceutil/trace.go:171","msg":"trace[539622930] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"428.531278ms","start":"2026-06-24T18:02:56.908543Z","end":"2026-06-24T18:02:57.337075Z","steps":["trace[539622930] 'process raft request' (duration: 428.145399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:57.337639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:56.992277Z","time spent":"345.334615ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T18:02:57.337814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:02:56.90851Z","time spent":"429.138532ms","remote":"127.0.0.1:44804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T18:02:58.749464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.952008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-24T18:02:58.749578Z","caller":"traceutil/trace.go:171","msg":"trace[1145788883] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1303; }","duration":"201.093662ms","start":"2026-06-24T18:02:58.548423Z","end":"2026-06-24T18:02:58.749517Z","steps":["trace[1145788883] 'range keys from in-memory index tree' (duration: 200.804225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:02:58.750084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.000188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T18:02:58.750239Z","caller":"traceutil/trace.go:171","msg":"trace[2069441637] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"198.694654ms","start":"2026-06-24T18:02:58.551496Z","end":"2026-06-24T18:02:58.75019Z","steps":["trace[2069441637] 'process raft request' (duration: 12.537042ms)","trace[2069441637] 'compare' (duration: 185.278331ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:02:58.853565Z","caller":"traceutil/trace.go:171","msg":"trace[669716757] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"117.11553ms","start":"2026-06-24T18:02:58.736415Z","end":"2026-06-24T18:02:58.853531Z","steps":["trace[669716757] 'process raft request' (duration: 114.922077ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:04.932872Z","caller":"traceutil/trace.go:171","msg":"trace[1989294737] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"164.102542ms","start":"2026-06-24T18:03:04.768742Z","end":"2026-06-24T18:03:04.932845Z","steps":["trace[1989294737] 'process raft request' (duration: 163.944418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:05.385748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.770866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T18:03:05.385759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.013645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T18:03:05.385821Z","caller":"traceutil/trace.go:171","msg":"trace[927789911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"100.896039ms","start":"2026-06-24T18:03:05.284909Z","end":"2026-06-24T18:03:05.385805Z","steps":["trace[927789911] 'range keys from in-memory index tree' (duration: 100.699104ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:03:05.385839Z","caller":"traceutil/trace.go:171","msg":"trace[1945034538] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1354; }","duration":"210.163579ms","start":"2026-06-24T18:03:05.175655Z","end":"2026-06-24T18:03:05.385819Z","steps":["trace[1945034538] 'count revisions from in-memory index tree' (duration: 209.883772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:05.386104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.857959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:03:05.386148Z","caller":"traceutil/trace.go:171","msg":"trace[1361767302] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1354; }","duration":"370.952001ms","start":"2026-06-24T18:03:05.015187Z","end":"2026-06-24T18:03:05.386139Z","steps":["trace[1361767302] 'count revisions from in-memory index tree' (duration: 370.751976ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:03:05.386183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T18:03:05.015154Z","time spent":"371.019383ms","remote":"127.0.0.1:44976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-06-24T18:04:28.010488Z","caller":"traceutil/trace.go:171","msg":"trace[395166388] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"142.831675ms","start":"2026-06-24T18:04:27.867643Z","end":"2026-06-24T18:04:28.010474Z","steps":["trace[395166388] 'process raft request' (duration: 142.805835ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:04:28.010538Z","caller":"traceutil/trace.go:171","msg":"trace[1888426526] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"144.859424ms","start":"2026-06-24T18:04:27.865663Z","end":"2026-06-24T18:04:28.010523Z","steps":["trace[1888426526] 'process raft request' (duration: 144.730521ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T18:04:28.010651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.405835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T18:04:28.010675Z","caller":"traceutil/trace.go:171","msg":"trace[229175797] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1738; }","duration":"116.459657ms","start":"2026-06-24T18:04:27.894208Z","end":"2026-06-24T18:04:28.010668Z","steps":["trace[229175797] 'agreement among raft nodes before linearized reading' (duration: 116.409516ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:02.309591Z","caller":"traceutil/trace.go:171","msg":"trace[1232426901] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"126.985514ms","start":"2026-06-24T18:05:02.182568Z","end":"2026-06-24T18:05:02.309553Z","steps":["trace[1232426901] 'process raft request' (duration: 126.894841ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:05:02.309634Z","caller":"traceutil/trace.go:171","msg":"trace[199870547] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"127.455165ms","start":"2026-06-24T18:05:02.182105Z","end":"2026-06-24T18:05:02.30956Z","steps":["trace[199870547] 'process raft request' (duration: 98.145104ms)","trace[199870547] 'compare' (duration: 29.036234ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T18:06:36.265399Z","caller":"traceutil/trace.go:171","msg":"trace[1690966863] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"169.071786ms","start":"2026-06-24T18:06:36.096305Z","end":"2026-06-24T18:06:36.265377Z","steps":["trace[1690966863] 'process raft request' (duration: 168.930943ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T18:06:36.267624Z","caller":"traceutil/trace.go:171","msg":"trace[807450689] transaction","detail":"{read_only:false; response_revision:2344; number_of_response:1; }","duration":"158.077094ms","start":"2026-06-24T18:06:36.109512Z","end":"2026-06-24T18:06:36.267589Z","steps":["trace[807450689] 'process raft request' (duration: 157.578122ms)"],"step_count":1}