{"level":"warn","ts":"2026-05-08T01:07:01.583176Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T01:07:01.583259Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T01:07:01.58333Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T01:07:01.583341Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-08T01:07:01.583359Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T01:07:01.584167Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-05-08T01:07:01.584263Z","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.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T01:07:01.58863Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.075652ms"} {"level":"info","ts":"2026-05-08T01:07:01.595757Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-08T01:07:01.595872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T01:07:01.595963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-08T01:07:01.59599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T01:07:01.596001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-08T01:07:01.59604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-08T01:07:01.603389Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T01:07:01.605613Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T01:07:01.609231Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T01:07:01.611213Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T01:07:01.611353Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T01:07:01.611466Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T01:07:01.611477Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T01:07:01.61154Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T01:07:01.613328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-08T01:07:01.613674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-08T01:07:01.616057Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T01:07:01.616257Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T01:07:01.61629Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T01:07:01.616406Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-08T01:07:01.616423Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-08T01:07:01.996969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T01:07:01.997089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T01:07:01.997159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-08T01:07:01.997215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-08T01:07:01.99724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-08T01:07:01.997322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-08T01:07:01.997355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-08T01:07:02.000894Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T01:07:02.001118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T01:07:02.001223Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T01:07:02.0012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T01:07:02.001558Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T01:07:02.00168Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T01:07:02.002114Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T01:07:02.002213Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T01:07:02.002241Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T01:07:02.004371Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-05-08T01:07:02.007448Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T01:07:04.73719Z","caller":"traceutil/trace.go:171","msg":"trace[2143505571] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"119.271465ms","start":"2026-05-08T01:07:04.61788Z","end":"2026-05-08T01:07:04.737152Z","steps":["trace[2143505571] 'process raft request' (duration: 41.126986ms)","trace[2143505571] 'compare' (duration: 77.952945ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:07:04.946449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.949788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-08T01:07:04.94657Z","caller":"traceutil/trace.go:171","msg":"trace[1552542263] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:0; response_revision:119; }","duration":"107.121122ms","start":"2026-05-08T01:07:04.839424Z","end":"2026-05-08T01:07:04.946545Z","steps":["trace[1552542263] 'range keys from in-memory index tree' (duration: 106.854806ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:04.946746Z","caller":"traceutil/trace.go:171","msg":"trace[1844799655] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"106.462767ms","start":"2026-05-08T01:07:04.840222Z","end":"2026-05-08T01:07:04.946684Z","steps":["trace[1844799655] 'process raft request' (duration: 41.001623ms)","trace[1844799655] 'compare' (duration: 65.218339ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:07:06.360669Z","caller":"traceutil/trace.go:171","msg":"trace[1471486128] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"312.534057ms","start":"2026-05-08T01:07:06.048111Z","end":"2026-05-08T01:07:06.360645Z","steps":["trace[1471486128] 'read index received' (duration: 312.328983ms)","trace[1471486128] 'applied index is now lower than readState.Index' (duration: 204.134µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:07:06.360825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.711321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-05-08T01:07:06.360856Z","caller":"traceutil/trace.go:171","msg":"trace[1674180909] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:245; }","duration":"312.818714ms","start":"2026-05-08T01:07:06.048029Z","end":"2026-05-08T01:07:06.360847Z","steps":["trace[1674180909] 'agreement among raft nodes before linearized reading' (duration: 312.712191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:06.360888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:06.047996Z","time spent":"312.884474ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-08T01:07:06.361045Z","caller":"traceutil/trace.go:171","msg":"trace[2009427744] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"319.280698ms","start":"2026-05-08T01:07:06.04173Z","end":"2026-05-08T01:07:06.361011Z","steps":["trace[2009427744] 'process raft request' (duration: 318.760657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:06.361546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:06.041706Z","time spent":"319.410142ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T01:07:07.431135Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741940762566198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T01:07:07.572195Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.204142352s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T01:07:07.573168Z","caller":"traceutil/trace.go:171","msg":"trace[668527895] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"1.205178725s","start":"2026-05-08T01:07:06.367954Z","end":"2026-05-08T01:07:07.573133Z","steps":["trace[668527895] 'process raft request' (duration: 1.204938379s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:07.573579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:06.367938Z","time spent":"1.20536968s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:07:07.958671Z","caller":"traceutil/trace.go:171","msg":"trace[976383902] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"1.028375405s","start":"2026-05-08T01:07:06.930268Z","end":"2026-05-08T01:07:07.958643Z","steps":["trace[976383902] 'read index received' (duration: 642.762463ms)","trace[976383902] 'applied index is now lower than readState.Index' (duration: 385.612012ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:07:07.958753Z","caller":"traceutil/trace.go:171","msg":"trace[1032056893] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"1.58912643s","start":"2026-05-08T01:07:06.369596Z","end":"2026-05-08T01:07:07.958722Z","steps":["trace[1032056893] 'process raft request' (duration: 1.527201525s)","trace[1032056893] 'compare' (duration: 60.291308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:07:07.958872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:06.369573Z","time spent":"1.589240843s","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:07:07.958949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.734768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T01:07:07.958944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028664861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:07:07.958994Z","caller":"traceutil/trace.go:171","msg":"trace[1140535840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:248; }","duration":"1.028763623s","start":"2026-05-08T01:07:06.930221Z","end":"2026-05-08T01:07:07.958984Z","steps":["trace[1140535840] 'agreement among raft nodes before linearized reading' (duration: 1.028669771s)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:07.958997Z","caller":"traceutil/trace.go:171","msg":"trace[950190482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:248; }","duration":"903.83534ms","start":"2026-05-08T01:07:07.055147Z","end":"2026-05-08T01:07:07.958982Z","steps":["trace[950190482] 'agreement among raft nodes before linearized reading' (duration: 903.717408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:07.958987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.715985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T01:07:07.959021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:07.055123Z","time spent":"903.892752ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T01:07:07.959065Z","caller":"traceutil/trace.go:171","msg":"trace[547763556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:248; }","duration":"282.836588ms","start":"2026-05-08T01:07:07.676205Z","end":"2026-05-08T01:07:07.959042Z","steps":["trace[547763556] 'agreement among raft nodes before linearized reading' (duration: 282.704385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:07.959019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:06.930199Z","time spent":"1.028814565s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T01:07:07.960119Z","caller":"traceutil/trace.go:171","msg":"trace[805430020] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"443.170639ms","start":"2026-05-08T01:07:07.516932Z","end":"2026-05-08T01:07:07.960103Z","steps":["trace[805430020] 'process raft request' (duration: 441.637744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:07.960194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:07.516907Z","time spent":"443.24611ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T01:07:08.153877Z","caller":"traceutil/trace.go:171","msg":"trace[1233667979] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"191.732057ms","start":"2026-05-08T01:07:07.962119Z","end":"2026-05-08T01:07:08.153851Z","steps":["trace[1233667979] 'process raft request' (duration: 191.048552ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:08.154487Z","caller":"traceutil/trace.go:171","msg":"trace[1633609239] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"192.081175ms","start":"2026-05-08T01:07:07.96239Z","end":"2026-05-08T01:07:08.154471Z","steps":["trace[1633609239] 'process raft request' (duration: 191.878911ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:08.154535Z","caller":"traceutil/trace.go:171","msg":"trace[1101355253] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"192.089435ms","start":"2026-05-08T01:07:07.962406Z","end":"2026-05-08T01:07:08.154496Z","steps":["trace[1101355253] 'process raft request' (duration: 191.959952ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:08.154537Z","caller":"traceutil/trace.go:171","msg":"trace[1805243863] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"189.473946ms","start":"2026-05-08T01:07:07.964993Z","end":"2026-05-08T01:07:08.154467Z","steps":["trace[1805243863] 'process raft request' (duration: 189.421575ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:42.421587Z","caller":"traceutil/trace.go:171","msg":"trace[1293166099] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"216.286308ms","start":"2026-05-08T01:07:42.205266Z","end":"2026-05-08T01:07:42.421553Z","steps":["trace[1293166099] 'process raft request' (duration: 212.718978ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:49.983656Z","caller":"traceutil/trace.go:171","msg":"trace[1654851141] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"213.372654ms","start":"2026-05-08T01:07:49.770254Z","end":"2026-05-08T01:07:49.983627Z","steps":["trace[1654851141] 'process raft request' (duration: 213.112819ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:50.152813Z","caller":"traceutil/trace.go:171","msg":"trace[1646171648] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"333.029549ms","start":"2026-05-08T01:07:49.819757Z","end":"2026-05-08T01:07:50.152786Z","steps":["trace[1646171648] 'process raft request' (duration: 332.467757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:50.152987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:49.819726Z","time spent":"333.173262ms","remote":"127.0.0.1:52590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:07:50.759693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741940762566999,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T01:07:50.901787Z","caller":"traceutil/trace.go:171","msg":"trace[1625051048] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"696.474522ms","start":"2026-05-08T01:07:50.205278Z","end":"2026-05-08T01:07:50.901753Z","steps":["trace[1625051048] 'process raft request' (duration: 625.398932ms)","trace[1625051048] 'compare' (duration: 70.850425ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:07:50.901981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.205249Z","time spent":"696.653326ms","remote":"127.0.0.1:53016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T01:07:50.902089Z","caller":"traceutil/trace.go:171","msg":"trace[2014934237] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"696.137434ms","start":"2026-05-08T01:07:50.20593Z","end":"2026-05-08T01:07:50.902068Z","steps":["trace[2014934237] 'process raft request' (duration: 695.736695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:50.902211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.205904Z","time spent":"696.225416ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:07:50.969887Z","caller":"traceutil/trace.go:171","msg":"trace[2055276225] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:669; }","duration":"711.06847ms","start":"2026-05-08T01:07:50.258757Z","end":"2026-05-08T01:07:50.969825Z","steps":["trace[2055276225] 'read index received' (duration: 571.918578ms)","trace[2055276225] 'applied index is now lower than readState.Index' (duration: 139.147972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:07:50.970306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.276857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:07:50.970374Z","caller":"traceutil/trace.go:171","msg":"trace[255142553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"199.383449ms","start":"2026-05-08T01:07:50.770969Z","end":"2026-05-08T01:07:50.970353Z","steps":["trace[255142553] 'agreement among raft nodes before linearized reading' (duration: 199.257966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:50.970457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.857867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-08T01:07:50.970479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.846261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T01:07:50.970552Z","caller":"traceutil/trace.go:171","msg":"trace[2115680088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"497.184464ms","start":"2026-05-08T01:07:50.47335Z","end":"2026-05-08T01:07:50.970535Z","steps":["trace[2115680088] 'agreement among raft nodes before linearized reading' (duration: 496.781445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:50.970616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.175252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T01:07:50.970659Z","caller":"traceutil/trace.go:171","msg":"trace[272899495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"484.238623ms","start":"2026-05-08T01:07:50.486408Z","end":"2026-05-08T01:07:50.970646Z","steps":["trace[272899495] 'agreement among raft nodes before linearized reading' (duration: 484.1159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:50.970126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.353827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zdn6h\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T01:07:50.970573Z","caller":"traceutil/trace.go:171","msg":"trace[2048680771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"482.951194ms","start":"2026-05-08T01:07:50.487596Z","end":"2026-05-08T01:07:50.970547Z","steps":["trace[2048680771] 'agreement among raft nodes before linearized reading' (duration: 482.694208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:50.970699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.486396Z","time spent":"484.295804ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T01:07:50.970724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.487568Z","time spent":"483.145998ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T01:07:50.970764Z","caller":"traceutil/trace.go:171","msg":"trace[1863663793] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zdn6h; range_end:; response_count:1; response_revision:641; }","duration":"711.974741ms","start":"2026-05-08T01:07:50.25873Z","end":"2026-05-08T01:07:50.970705Z","steps":["trace[1863663793] 'agreement among raft nodes before linearized reading' (duration: 711.233445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:50.970669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.473314Z","time spent":"497.309287ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T01:07:50.971024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.25872Z","time spent":"712.290748ms","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-zdn6h\" "} {"level":"warn","ts":"2026-05-08T01:07:52.2224Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.24751044s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T01:07:52.222894Z","caller":"traceutil/trace.go:171","msg":"trace[1010711268] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.248158924s","start":"2026-05-08T01:07:50.974712Z","end":"2026-05-08T01:07:52.222871Z","steps":["trace[1010711268] 'process raft request' (duration: 1.247887958s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:52.223033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.974692Z","time spent":"1.248267697s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:07:53.009386Z","caller":"traceutil/trace.go:171","msg":"trace[1810473210] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.033469067s","start":"2026-05-08T01:07:50.975896Z","end":"2026-05-08T01:07:53.009365Z","steps":["trace[1810473210] 'process raft request' (duration: 2.033378085s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:53.009515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.975891Z","time spent":"2.033558979s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:07:53.009393Z","caller":"traceutil/trace.go:171","msg":"trace[67078700] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.034284595s","start":"2026-05-08T01:07:50.97509Z","end":"2026-05-08T01:07:53.009375Z","steps":["trace[67078700] 'process raft request' (duration: 2.03406597s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:53.009731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.975073Z","time spent":"2.034625963s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:07:53.010124Z","caller":"traceutil/trace.go:171","msg":"trace[711431011] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.024793522s","start":"2026-05-08T01:07:50.985303Z","end":"2026-05-08T01:07:53.010097Z","steps":["trace[711431011] 'process raft request' (duration: 2.024016574s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:53.010306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:50.985295Z","time spent":"2.024923725s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:07:53.015092Z","caller":"traceutil/trace.go:171","msg":"trace[1865976005] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:672; }","duration":"1.026374983s","start":"2026-05-08T01:07:51.988621Z","end":"2026-05-08T01:07:53.014996Z","steps":["trace[1865976005] 'read index received' (duration: 234.076166ms)","trace[1865976005] 'applied index is now lower than readState.Index' (duration: 792.296007ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:07:53.015468Z","caller":"traceutil/trace.go:171","msg":"trace[1788898995] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.666833028s","start":"2026-05-08T01:07:51.348601Z","end":"2026-05-08T01:07:53.015434Z","steps":["trace[1788898995] 'process raft request' (duration: 1.662047363s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:53.015611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:51.348564Z","time spent":"1.6669502s","remote":"127.0.0.1:52590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:07:53.016024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027407327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T01:07:53.016112Z","caller":"traceutil/trace.go:171","msg":"trace[1999752588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"1.02752543s","start":"2026-05-08T01:07:51.988568Z","end":"2026-05-08T01:07:53.016093Z","steps":["trace[1999752588] 'agreement among raft nodes before linearized reading' (duration: 1.027331696s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:07:53.01616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:07:51.988545Z","time spent":"1.027603873s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T01:07:53.016419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.84374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:07:53.01647Z","caller":"traceutil/trace.go:171","msg":"trace[1251881288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"246.915602ms","start":"2026-05-08T01:07:52.769538Z","end":"2026-05-08T01:07:53.016454Z","steps":["trace[1251881288] 'agreement among raft nodes before linearized reading' (duration: 246.83069ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:07:54.823412Z","caller":"traceutil/trace.go:171","msg":"trace[1563550124] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"294.118339ms","start":"2026-05-08T01:07:54.52927Z","end":"2026-05-08T01:07:54.823388Z","steps":["trace[1563550124] 'process raft request' (duration: 293.942715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:08:07.301267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.513027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-08T01:08:07.301449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.502822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T01:08:07.30193Z","caller":"traceutil/trace.go:171","msg":"trace[976858199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"235.231724ms","start":"2026-05-08T01:08:07.066678Z","end":"2026-05-08T01:08:07.30191Z","steps":["trace[976858199] 'range keys from in-memory index tree' (duration: 234.336842ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:08:07.302067Z","caller":"traceutil/trace.go:171","msg":"trace[492428746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"234.159008ms","start":"2026-05-08T01:08:07.067887Z","end":"2026-05-08T01:08:07.302046Z","steps":["trace[492428746] 'range keys from in-memory index tree' (duration: 233.266697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:08:07.301568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.441021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T01:08:07.302253Z","caller":"traceutil/trace.go:171","msg":"trace[2024471156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"229.171838ms","start":"2026-05-08T01:08:07.073053Z","end":"2026-05-08T01:08:07.302225Z","steps":["trace[2024471156] 'range keys from in-memory index tree' (duration: 228.246737ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:08:07.412966Z","caller":"traceutil/trace.go:171","msg":"trace[1178314590] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"106.481321ms","start":"2026-05-08T01:08:07.306461Z","end":"2026-05-08T01:08:07.412942Z","steps":["trace[1178314590] 'process raft request' (duration: 106.339018ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:08:07.418206Z","caller":"traceutil/trace.go:171","msg":"trace[1582484707] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"111.335437ms","start":"2026-05-08T01:08:07.306822Z","end":"2026-05-08T01:08:07.418158Z","steps":["trace[1582484707] 'process raft request' (duration: 111.195974ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:08:07.418575Z","caller":"traceutil/trace.go:171","msg":"trace[61608092] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"111.468151ms","start":"2026-05-08T01:08:07.307095Z","end":"2026-05-08T01:08:07.418563Z","steps":["trace[61608092] 'process raft request' (duration: 111.032201ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:08:42.790863Z","caller":"traceutil/trace.go:171","msg":"trace[1881337742] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"225.403599ms","start":"2026-05-08T01:08:42.565434Z","end":"2026-05-08T01:08:42.790838Z","steps":["trace[1881337742] 'read index received' (duration: 225.170613ms)","trace[1881337742] 'applied index is now lower than readState.Index' (duration: 232.136µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:08:42.790937Z","caller":"traceutil/trace.go:171","msg":"trace[1174240400] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"323.890349ms","start":"2026-05-08T01:08:42.467016Z","end":"2026-05-08T01:08:42.790907Z","steps":["trace[1174240400] 'process raft request' (duration: 323.682734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:08:42.792233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:08:42.467001Z","time spent":"324.005423ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:08:42.792253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.809643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T01:08:42.792308Z","caller":"traceutil/trace.go:171","msg":"trace[911844444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1024; }","duration":"226.901044ms","start":"2026-05-08T01:08:42.565392Z","end":"2026-05-08T01:08:42.792293Z","steps":["trace[911844444] 'agreement among raft nodes before linearized reading' (duration: 225.575343ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:08:43.122398Z","caller":"traceutil/trace.go:171","msg":"trace[1558405904] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"325.898907ms","start":"2026-05-08T01:08:42.796476Z","end":"2026-05-08T01:08:43.122375Z","steps":["trace[1558405904] 'process raft request' (duration: 257.654079ms)","trace[1558405904] 'compare' (duration: 68.140185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:08:43.122516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:08:42.79646Z","time spent":"325.995729ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:05.606432Z","caller":"traceutil/trace.go:171","msg":"trace[1516609413] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"148.00106ms","start":"2026-05-08T01:09:05.458406Z","end":"2026-05-08T01:09:05.606407Z","steps":["trace[1516609413] 'read index received' (duration: 147.848106ms)","trace[1516609413] 'applied index is now lower than readState.Index' (duration: 151.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:09:05.606535Z","caller":"traceutil/trace.go:171","msg":"trace[510791202] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"366.096241ms","start":"2026-05-08T01:09:05.240393Z","end":"2026-05-08T01:09:05.60649Z","steps":["trace[510791202] 'process raft request' (duration: 365.871305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:05.606772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.240378Z","time spent":"366.282316ms","remote":"127.0.0.1:52744","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-05-08T01:09:05.606846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.428309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T01:09:05.606942Z","caller":"traceutil/trace.go:171","msg":"trace[1849339604] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1097; }","duration":"148.602194ms","start":"2026-05-08T01:09:05.458328Z","end":"2026-05-08T01:09:05.60693Z","steps":["trace[1849339604] 'agreement among raft nodes before linearized reading' (duration: 148.179524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:06.669332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.193626ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:09:06.669753Z","caller":"traceutil/trace.go:171","msg":"trace[590213678] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1097; }","duration":"744.33886ms","start":"2026-05-08T01:09:05.925105Z","end":"2026-05-08T01:09:06.669444Z","steps":["trace[590213678] 'range keys from in-memory index tree' (duration: 744.165446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:06.670567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.317146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:09:06.670811Z","caller":"traceutil/trace.go:171","msg":"trace[1033394218] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"1.136717394s","start":"2026-05-08T01:09:05.534073Z","end":"2026-05-08T01:09:06.67079Z","steps":["trace[1033394218] 'process raft request' (duration: 602.381173ms)","trace[1033394218] 'compare' (duration: 533.114142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:06.670905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.534051Z","time spent":"1.136808636s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:06.67094Z","caller":"traceutil/trace.go:171","msg":"trace[1645935315] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1144; }","duration":"995.851184ms","start":"2026-05-08T01:09:05.67507Z","end":"2026-05-08T01:09:06.670921Z","steps":["trace[1645935315] 'read index received' (duration: 461.39071ms)","trace[1645935315] 'applied index is now lower than readState.Index' (duration: 534.459024ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:06.670932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.607833Z","time spent":"1.063092179s","remote":"127.0.0.1:52538","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-08T01:09:06.671259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.371028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T01:09:06.671268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.187752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T01:09:06.67129Z","caller":"traceutil/trace.go:171","msg":"trace[1277914325] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"913.00169ms","start":"2026-05-08T01:09:05.758081Z","end":"2026-05-08T01:09:06.671082Z","steps":["trace[1277914325] 'process raft request' (duration: 912.668512ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:06.671703Z","caller":"traceutil/trace.go:171","msg":"trace[1167952606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"996.627862ms","start":"2026-05-08T01:09:05.675039Z","end":"2026-05-08T01:09:06.671667Z","steps":["trace[1167952606] 'agreement among raft nodes before linearized reading' (duration: 995.996577ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:06.671866Z","caller":"traceutil/trace.go:171","msg":"trace[263323185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"991.722206ms","start":"2026-05-08T01:09:05.679836Z","end":"2026-05-08T01:09:06.671558Z","steps":["trace[263323185] 'agreement among raft nodes before linearized reading' (duration: 991.320546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:06.671959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.675025Z","time spent":"996.913879ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T01:09:06.671966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.679823Z","time spent":"992.124175ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T01:09:06.671889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.758047Z","time spent":"913.787498ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:09:06.671371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.92723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:09:06.672146Z","caller":"traceutil/trace.go:171","msg":"trace[1976899801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"903.729969ms","start":"2026-05-08T01:09:05.768405Z","end":"2026-05-08T01:09:06.672135Z","steps":["trace[1976899801] 'agreement among raft nodes before linearized reading' (duration: 902.800417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:06.67219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.768396Z","time spent":"903.77885ms","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T01:09:06.671365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.90914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T01:09:06.672454Z","caller":"traceutil/trace.go:171","msg":"trace[2039603435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"958.032187ms","start":"2026-05-08T01:09:05.714404Z","end":"2026-05-08T01:09:06.672436Z","steps":["trace[2039603435] 'agreement among raft nodes before linearized reading' (duration: 956.88975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:06.672509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:05.714379Z","time spent":"958.115369ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T01:09:06.807924Z","caller":"traceutil/trace.go:171","msg":"trace[1303567035] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"130.964506ms","start":"2026-05-08T01:09:06.67694Z","end":"2026-05-08T01:09:06.807905Z","steps":["trace[1303567035] 'process raft request' (duration: 130.869824ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:06.807993Z","caller":"traceutil/trace.go:171","msg":"trace[1538916121] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"131.5547ms","start":"2026-05-08T01:09:06.676427Z","end":"2026-05-08T01:09:06.807981Z","steps":["trace[1538916121] 'process raft request' (duration: 121.956902ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:06.807924Z","caller":"traceutil/trace.go:171","msg":"trace[1095491616] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"131.379166ms","start":"2026-05-08T01:09:06.676514Z","end":"2026-05-08T01:09:06.807893Z","steps":["trace[1095491616] 'process raft request' (duration: 131.256673ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:07.951419Z","caller":"traceutil/trace.go:171","msg":"trace[795488748] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"181.125835ms","start":"2026-05-08T01:09:07.770219Z","end":"2026-05-08T01:09:07.951345Z","steps":["trace[795488748] 'read index received' (duration: 180.963211ms)","trace[795488748] 'applied index is now lower than readState.Index' (duration: 161.864µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:07.951501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.268028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:09:07.95155Z","caller":"traceutil/trace.go:171","msg":"trace[163393326] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"336.921429ms","start":"2026-05-08T01:09:07.614454Z","end":"2026-05-08T01:09:07.951376Z","steps":["trace[163393326] 'process raft request' (duration: 336.728655ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:07.951559Z","caller":"traceutil/trace.go:171","msg":"trace[1201298364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"181.407271ms","start":"2026-05-08T01:09:07.770136Z","end":"2026-05-08T01:09:07.951543Z","steps":["trace[1201298364] 'agreement among raft nodes before linearized reading' (duration: 181.312219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:07.951654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:07.614433Z","time spent":"337.142934ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:21.208954Z","caller":"traceutil/trace.go:171","msg":"trace[1507252925] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"181.714798ms","start":"2026-05-08T01:09:21.027204Z","end":"2026-05-08T01:09:21.208919Z","steps":["trace[1507252925] 'process raft request' (duration: 181.554875ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:26.765574Z","caller":"traceutil/trace.go:171","msg":"trace[1354235802] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"718.573399ms","start":"2026-05-08T01:09:26.046987Z","end":"2026-05-08T01:09:26.765561Z","steps":["trace[1354235802] 'process raft request' (duration: 718.433956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:26.765697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:26.046976Z","time spent":"718.655401ms","remote":"127.0.0.1:52744","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-05-08T01:09:26.765831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.70481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:09:26.765576Z","caller":"traceutil/trace.go:171","msg":"trace[661765702] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1270; }","duration":"149.426693ms","start":"2026-05-08T01:09:26.616128Z","end":"2026-05-08T01:09:26.765555Z","steps":["trace[661765702] 'read index received' (duration: 149.419573ms)","trace[661765702] 'applied index is now lower than readState.Index' (duration: 5.83µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:09:26.765918Z","caller":"traceutil/trace.go:171","msg":"trace[1342362518] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1216; }","duration":"149.785782ms","start":"2026-05-08T01:09:26.616073Z","end":"2026-05-08T01:09:26.765859Z","steps":["trace[1342362518] 'agreement among raft nodes before linearized reading' (duration: 149.546446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:27.559572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.423518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:09:27.559828Z","caller":"traceutil/trace.go:171","msg":"trace[1772288680] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"806.34443ms","start":"2026-05-08T01:09:26.753438Z","end":"2026-05-08T01:09:27.559782Z","steps":["trace[1772288680] 'process raft request' (duration: 501.608444ms)","trace[1772288680] 'compare' (duration: 304.05442ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:27.560014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:26.753405Z","time spent":"806.505804ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:27.560018Z","caller":"traceutil/trace.go:171","msg":"trace[968567359] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"791.746354ms","start":"2026-05-08T01:09:26.76825Z","end":"2026-05-08T01:09:27.559996Z","steps":["trace[968567359] 'read index received' (duration: 486.828184ms)","trace[968567359] 'applied index is now lower than readState.Index' (duration: 304.9155ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:27.560108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.848447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:09:27.560143Z","caller":"traceutil/trace.go:171","msg":"trace[1455908856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"791.904818ms","start":"2026-05-08T01:09:26.768229Z","end":"2026-05-08T01:09:27.560133Z","steps":["trace[1455908856] 'agreement among raft nodes before linearized reading' (duration: 791.845337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:27.560175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:26.768217Z","time spent":"791.951829ms","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T01:09:27.560247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.674971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T01:09:27.56028Z","caller":"traceutil/trace.go:171","msg":"trace[912587703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"602.711442ms","start":"2026-05-08T01:09:26.957559Z","end":"2026-05-08T01:09:27.56027Z","steps":["trace[912587703] 'agreement among raft nodes before linearized reading' (duration: 602.555878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:27.56028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.953111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-08T01:09:27.560361Z","caller":"traceutil/trace.go:171","msg":"trace[1821224958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"324.079584ms","start":"2026-05-08T01:09:27.236263Z","end":"2026-05-08T01:09:27.560343Z","steps":["trace[1821224958] 'agreement among raft nodes before linearized reading' (duration: 323.87688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:27.560395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.045938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T01:09:27.560377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.317127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T01:09:27.560423Z","caller":"traceutil/trace.go:171","msg":"trace[920987791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"600.10746ms","start":"2026-05-08T01:09:26.960309Z","end":"2026-05-08T01:09:27.560416Z","steps":["trace[920987791] 'agreement among raft nodes before linearized reading' (duration: 600.025138ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:27.560446Z","caller":"traceutil/trace.go:171","msg":"trace[460071531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"595.390428ms","start":"2026-05-08T01:09:26.965039Z","end":"2026-05-08T01:09:27.560429Z","steps":["trace[460071531] 'agreement among raft nodes before linearized reading' (duration: 595.156863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:27.560454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:26.960289Z","time spent":"600.159811ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T01:09:27.56005Z","caller":"traceutil/trace.go:171","msg":"trace[1710491949] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"651.328415ms","start":"2026-05-08T01:09:26.908704Z","end":"2026-05-08T01:09:27.560032Z","steps":["trace[1710491949] 'process raft request' (duration: 650.990157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:27.560492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:26.965009Z","time spent":"595.47202ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T01:09:27.5604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:27.236239Z","time spent":"324.153347ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T01:09:27.560309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:26.957508Z","time spent":"602.793963ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T01:09:27.560811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:26.908679Z","time spent":"651.936299ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:27.778661Z","caller":"traceutil/trace.go:171","msg":"trace[990382766] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"214.724722ms","start":"2026-05-08T01:09:27.563911Z","end":"2026-05-08T01:09:27.778636Z","steps":["trace[990382766] 'process raft request' (duration: 193.292473ms)","trace[990382766] 'compare' (duration: 21.275125ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:09:27.780032Z","caller":"traceutil/trace.go:171","msg":"trace[1686522339] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"213.303978ms","start":"2026-05-08T01:09:27.56671Z","end":"2026-05-08T01:09:27.780014Z","steps":["trace[1686522339] 'process raft request' (duration: 213.004431ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:27.780014Z","caller":"traceutil/trace.go:171","msg":"trace[1681075632] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"214.686571ms","start":"2026-05-08T01:09:27.565298Z","end":"2026-05-08T01:09:27.779985Z","steps":["trace[1681075632] 'process raft request' (duration: 214.380854ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:27.780033Z","caller":"traceutil/trace.go:171","msg":"trace[115301831] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"215.329926ms","start":"2026-05-08T01:09:27.564673Z","end":"2026-05-08T01:09:27.780003Z","steps":["trace[115301831] 'process raft request' (duration: 214.901126ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:38.963925Z","caller":"traceutil/trace.go:171","msg":"trace[968326138] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"146.503434ms","start":"2026-05-08T01:09:38.817401Z","end":"2026-05-08T01:09:38.963904Z","steps":["trace[968326138] 'process raft request' (duration: 146.374181ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:41.865289Z","caller":"traceutil/trace.go:171","msg":"trace[1805352806] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"255.327714ms","start":"2026-05-08T01:09:41.609933Z","end":"2026-05-08T01:09:41.86526Z","steps":["trace[1805352806] 'process raft request' (duration: 255.090658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:42.975685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.456294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:09:42.975783Z","caller":"traceutil/trace.go:171","msg":"trace[1157482023] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.105862823s","start":"2026-05-08T01:09:41.8699Z","end":"2026-05-08T01:09:42.975763Z","steps":["trace[1157482023] 'process raft request' (duration: 603.245294ms)","trace[1157482023] 'compare' (duration: 502.25917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:42.975846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:41.869887Z","time spent":"1.105927044s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:43.118072Z","caller":"traceutil/trace.go:171","msg":"trace[574100740] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.248020123s","start":"2026-05-08T01:09:41.870028Z","end":"2026-05-08T01:09:43.118049Z","steps":["trace[574100740] 'process raft request' (duration: 1.247945192s)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:43.118085Z","caller":"traceutil/trace.go:171","msg":"trace[1871618960] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.248068235s","start":"2026-05-08T01:09:41.869992Z","end":"2026-05-08T01:09:43.118061Z","steps":["trace[1871618960] 'process raft request' (duration: 1.236980952s)","trace[1871618960] 'compare' (duration: 10.870048ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:43.118268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:41.870022Z","time spent":"1.248120616s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:09:43.118327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:41.869964Z","time spent":"1.24829992s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:43.118349Z","caller":"traceutil/trace.go:171","msg":"trace[861832921] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.247745137s","start":"2026-05-08T01:09:41.87058Z","end":"2026-05-08T01:09:43.118325Z","steps":["trace[861832921] 'process raft request' (duration: 1.24743492s)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:43.118384Z","caller":"traceutil/trace.go:171","msg":"trace[252802339] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1329; }","duration":"347.974061ms","start":"2026-05-08T01:09:42.770237Z","end":"2026-05-08T01:09:43.118212Z","steps":["trace[252802339] 'read index received' (duration: 336.719074ms)","trace[252802339] 'applied index is now lower than readState.Index' (duration: 11.253787ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:43.118471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:41.870549Z","time spent":"1.247862929s","remote":"127.0.0.1:52744","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-05-08T01:09:43.118538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.305319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:09:43.118562Z","caller":"traceutil/trace.go:171","msg":"trace[1311837848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"348.36562ms","start":"2026-05-08T01:09:42.77019Z","end":"2026-05-08T01:09:43.118556Z","steps":["trace[1311837848] 'agreement among raft nodes before linearized reading' (duration: 348.314699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:43.11858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:42.770153Z","time spent":"348.424212ms","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T01:09:43.133166Z","caller":"traceutil/trace.go:171","msg":"trace[2118546446] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"105.916442ms","start":"2026-05-08T01:09:43.027235Z","end":"2026-05-08T01:09:43.133151Z","steps":["trace[2118546446] 'process raft request' (duration: 105.782208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:43.133272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.768738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T01:09:43.133337Z","caller":"traceutil/trace.go:171","msg":"trace[1019292855] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1276; }","duration":"105.873271ms","start":"2026-05-08T01:09:43.027451Z","end":"2026-05-08T01:09:43.133325Z","steps":["trace[1019292855] 'agreement among raft nodes before linearized reading' (duration: 105.703647ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:45.335985Z","caller":"traceutil/trace.go:171","msg":"trace[646570124] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"237.006769ms","start":"2026-05-08T01:09:45.098955Z","end":"2026-05-08T01:09:45.335961Z","steps":["trace[646570124] 'read index received' (duration: 236.814035ms)","trace[646570124] 'applied index is now lower than readState.Index' (duration: 191.674µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:09:45.33608Z","caller":"traceutil/trace.go:171","msg":"trace[1815754019] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"352.618741ms","start":"2026-05-08T01:09:44.983427Z","end":"2026-05-08T01:09:45.336046Z","steps":["trace[1815754019] 'process raft request' (duration: 352.388446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:45.336247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.262836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T01:09:45.336288Z","caller":"traceutil/trace.go:171","msg":"trace[151801318] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1280; }","duration":"237.332218ms","start":"2026-05-08T01:09:45.098945Z","end":"2026-05-08T01:09:45.336277Z","steps":["trace[151801318] 'agreement among raft nodes before linearized reading' (duration: 237.184954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:45.336333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.986614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-08T01:09:45.336396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:44.98341Z","time spent":"352.735564ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:09:45.336391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.953513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T01:09:45.336459Z","caller":"traceutil/trace.go:171","msg":"trace[15649221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"214.044916ms","start":"2026-05-08T01:09:45.122399Z","end":"2026-05-08T01:09:45.336444Z","steps":["trace[15649221] 'agreement among raft nodes before linearized reading' (duration: 213.881232ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:45.336411Z","caller":"traceutil/trace.go:171","msg":"trace[1326594139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"214.117228ms","start":"2026-05-08T01:09:45.122275Z","end":"2026-05-08T01:09:45.336393Z","steps":["trace[1326594139] 'agreement among raft nodes before linearized reading' (duration: 213.870622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:45.336875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.480026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T01:09:45.336917Z","caller":"traceutil/trace.go:171","msg":"trace[1216890284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"214.540108ms","start":"2026-05-08T01:09:45.122362Z","end":"2026-05-08T01:09:45.336902Z","steps":["trace[1216890284] 'agreement among raft nodes before linearized reading' (duration: 214.077057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:45.337047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.806526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T01:09:45.337075Z","caller":"traceutil/trace.go:171","msg":"trace[110261901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"177.868728ms","start":"2026-05-08T01:09:45.159198Z","end":"2026-05-08T01:09:45.337067Z","steps":["trace[110261901] 'agreement among raft nodes before linearized reading' (duration: 177.336695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:46.528156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.393501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-05-08T01:09:46.528587Z","caller":"traceutil/trace.go:171","msg":"trace[1330156731] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1289; }","duration":"208.863563ms","start":"2026-05-08T01:09:46.319708Z","end":"2026-05-08T01:09:46.528572Z","steps":["trace[1330156731] 'range keys from in-memory index tree' (duration: 208.195146ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:46.80016Z","caller":"traceutil/trace.go:171","msg":"trace[392903700] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"263.979489ms","start":"2026-05-08T01:09:46.536154Z","end":"2026-05-08T01:09:46.800133Z","steps":["trace[392903700] 'process raft request' (duration: 263.765334ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:53.662647Z","caller":"traceutil/trace.go:171","msg":"trace[2121469066] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"266.407037ms","start":"2026-05-08T01:09:53.396214Z","end":"2026-05-08T01:09:53.662621Z","steps":["trace[2121469066] 'read index received' (duration: 266.183201ms)","trace[2121469066] 'applied index is now lower than readState.Index' (duration: 222.486µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:09:53.662759Z","caller":"traceutil/trace.go:171","msg":"trace[1655900479] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"287.793314ms","start":"2026-05-08T01:09:53.374941Z","end":"2026-05-08T01:09:53.662734Z","steps":["trace[1655900479] 'process raft request' (duration: 287.522498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:53.662901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.664843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T01:09:53.662963Z","caller":"traceutil/trace.go:171","msg":"trace[40016523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1359; }","duration":"266.763465ms","start":"2026-05-08T01:09:53.396189Z","end":"2026-05-08T01:09:53.662952Z","steps":["trace[40016523] 'agreement among raft nodes before linearized reading' (duration: 266.554971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:53.662996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.76368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T01:09:53.663039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.9091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T01:09:53.663128Z","caller":"traceutil/trace.go:171","msg":"trace[757435984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1359; }","duration":"251.956704ms","start":"2026-05-08T01:09:53.411153Z","end":"2026-05-08T01:09:53.663109Z","steps":["trace[757435984] 'agreement among raft nodes before linearized reading' (duration: 251.647007ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:53.663195Z","caller":"traceutil/trace.go:171","msg":"trace[349915763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1359; }","duration":"251.119105ms","start":"2026-05-08T01:09:53.412054Z","end":"2026-05-08T01:09:53.663173Z","steps":["trace[349915763] 'agreement among raft nodes before linearized reading' (duration: 250.771856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:53.663163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.79961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T01:09:53.663741Z","caller":"traceutil/trace.go:171","msg":"trace[48405740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1359; }","duration":"252.165189ms","start":"2026-05-08T01:09:53.411273Z","end":"2026-05-08T01:09:53.663438Z","steps":["trace[48405740] 'agreement among raft nodes before linearized reading' (duration: 251.625046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:54.662419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.309488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:09:54.662695Z","caller":"traceutil/trace.go:171","msg":"trace[818152855] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"995.843923ms","start":"2026-05-08T01:09:53.666835Z","end":"2026-05-08T01:09:54.662678Z","steps":["trace[818152855] 'process raft request' (duration: 483.208148ms)","trace[818152855] 'compare' (duration: 511.911157ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:54.662749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:53.666819Z","time spent":"995.906405ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:55.052022Z","caller":"traceutil/trace.go:171","msg":"trace[1733522135] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.383584367s","start":"2026-05-08T01:09:53.668406Z","end":"2026-05-08T01:09:55.05199Z","steps":["trace[1733522135] 'process raft request' (duration: 1.382967602s)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:55.052471Z","caller":"traceutil/trace.go:171","msg":"trace[1750679982] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.384032488s","start":"2026-05-08T01:09:53.668414Z","end":"2026-05-08T01:09:55.052446Z","steps":["trace[1750679982] 'process raft request' (duration: 1.383499895s)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:55.052576Z","caller":"traceutil/trace.go:171","msg":"trace[1936980407] linearizableReadLoop","detail":"{readStateIndex:1422; appliedIndex:1418; }","duration":"1.355952393s","start":"2026-05-08T01:09:53.696607Z","end":"2026-05-08T01:09:55.052559Z","steps":["trace[1936980407] 'read index received' (duration: 453.419032ms)","trace[1936980407] 'applied index is now lower than readState.Index' (duration: 902.532161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:09:55.052594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:53.6684Z","time spent":"1.38412357s","remote":"127.0.0.1:52744","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-05-08T01:09:55.052771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282893409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:09:55.052772Z","caller":"traceutil/trace.go:171","msg":"trace[1029419760] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.384036398s","start":"2026-05-08T01:09:53.668711Z","end":"2026-05-08T01:09:55.052748Z","steps":["trace[1029419760] 'process raft request' (duration: 1.383600088s)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:55.052808Z","caller":"traceutil/trace.go:171","msg":"trace[2133464777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"1.282955121s","start":"2026-05-08T01:09:53.76984Z","end":"2026-05-08T01:09:55.052796Z","steps":["trace[2133464777] 'agreement among raft nodes before linearized reading' (duration: 1.28289917s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:55.052897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:53.769823Z","time spent":"1.283063803s","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T01:09:55.052794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356146007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T01:09:55.053011Z","caller":"traceutil/trace.go:171","msg":"trace[1380030882] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1364; }","duration":"1.356379423s","start":"2026-05-08T01:09:53.696603Z","end":"2026-05-08T01:09:55.052982Z","steps":["trace[1380030882] 'agreement among raft nodes before linearized reading' (duration: 1.356081245s)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:55.052895Z","caller":"traceutil/trace.go:171","msg":"trace[511569559] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"1.11803811s","start":"2026-05-08T01:09:53.934831Z","end":"2026-05-08T01:09:55.052869Z","steps":["trace[511569559] 'process raft request' (duration: 1.117672331s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:55.052842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.19423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-08T01:09:55.053083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:53.696568Z","time spent":"1.356492515s","remote":"127.0.0.1:52938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-08T01:09:55.053078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.496094ms","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":"warn","ts":"2026-05-08T01:09:55.053089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:53.668389Z","time spent":"1.383712461s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:55.0531Z","caller":"traceutil/trace.go:171","msg":"trace[347401907] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1364; }","duration":"659.441636ms","start":"2026-05-08T01:09:54.393638Z","end":"2026-05-08T01:09:55.05308Z","steps":["trace[347401907] 'agreement among raft nodes before linearized reading' (duration: 659.133559ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:55.053136Z","caller":"traceutil/trace.go:171","msg":"trace[484034110] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1364; }","duration":"367.599467ms","start":"2026-05-08T01:09:54.68552Z","end":"2026-05-08T01:09:55.053119Z","steps":["trace[484034110] 'agreement among raft nodes before linearized reading' (duration: 367.256269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:09:55.053174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:54.685504Z","time spent":"367.664518ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-08T01:09:55.053177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:54.393569Z","time spent":"659.59104ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-08T01:09:55.05316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:53.934798Z","time spent":"1.118277286s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:09:55.052906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:09:53.668705Z","time spent":"1.38411763s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:09:57.253951Z","caller":"traceutil/trace.go:171","msg":"trace[1402254017] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"180.254034ms","start":"2026-05-08T01:09:57.073675Z","end":"2026-05-08T01:09:57.253929Z","steps":["trace[1402254017] 'process raft request' (duration: 179.847735ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:09:59.423158Z","caller":"traceutil/trace.go:171","msg":"trace[1241917931] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"160.870395ms","start":"2026-05-08T01:09:59.262265Z","end":"2026-05-08T01:09:59.423136Z","steps":["trace[1241917931] 'process raft request' (duration: 160.726161ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:08.551521Z","caller":"traceutil/trace.go:171","msg":"trace[1837352063] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"217.942367ms","start":"2026-05-08T01:10:08.333556Z","end":"2026-05-08T01:10:08.551499Z","steps":["trace[1837352063] 'process raft request' (duration: 217.800074ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:12.909776Z","caller":"traceutil/trace.go:171","msg":"trace[228404093] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"140.422138ms","start":"2026-05-08T01:10:12.769331Z","end":"2026-05-08T01:10:12.909754Z","steps":["trace[228404093] 'read index received' (duration: 140.254524ms)","trace[228404093] 'applied index is now lower than readState.Index' (duration: 162.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:12.909826Z","caller":"traceutil/trace.go:171","msg":"trace[468465890] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"176.550151ms","start":"2026-05-08T01:10:12.73325Z","end":"2026-05-08T01:10:12.9098Z","steps":["trace[468465890] 'process raft request' (duration: 176.362617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:12.90989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.538721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:12.909921Z","caller":"traceutil/trace.go:171","msg":"trace[1521595033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1443; }","duration":"140.618112ms","start":"2026-05-08T01:10:12.769294Z","end":"2026-05-08T01:10:12.909912Z","steps":["trace[1521595033] 'agreement among raft nodes before linearized reading' (duration: 140.54935ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:13.268809Z","caller":"traceutil/trace.go:171","msg":"trace[1417216671] linearizableReadLoop","detail":"{readStateIndex:1507; appliedIndex:1506; }","duration":"147.723798ms","start":"2026-05-08T01:10:13.121038Z","end":"2026-05-08T01:10:13.268762Z","steps":["trace[1417216671] 'read index received' (duration: 135.769599ms)","trace[1417216671] 'applied index is now lower than readState.Index' (duration: 11.952389ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:13.268827Z","caller":"traceutil/trace.go:171","msg":"trace[1813641736] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"161.827277ms","start":"2026-05-08T01:10:13.106962Z","end":"2026-05-08T01:10:13.268789Z","steps":["trace[1813641736] 'process raft request' (duration: 149.770506ms)","trace[1813641736] 'compare' (duration: 11.745624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:13.269092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.031295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T01:10:13.269134Z","caller":"traceutil/trace.go:171","msg":"trace[1662189838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1444; }","duration":"148.147548ms","start":"2026-05-08T01:10:13.120976Z","end":"2026-05-08T01:10:13.269124Z","steps":["trace[1662189838] 'agreement among raft nodes before linearized reading' (duration: 147.946273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:13.269303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.67475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-08T01:10:13.269354Z","caller":"traceutil/trace.go:171","msg":"trace[957495004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1444; }","duration":"139.746252ms","start":"2026-05-08T01:10:13.129596Z","end":"2026-05-08T01:10:13.269342Z","steps":["trace[957495004] 'agreement among raft nodes before linearized reading' (duration: 139.614439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:13.269495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.988427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T01:10:13.269562Z","caller":"traceutil/trace.go:171","msg":"trace[1955721429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1444; }","duration":"137.035839ms","start":"2026-05-08T01:10:13.132483Z","end":"2026-05-08T01:10:13.269519Z","steps":["trace[1955721429] 'agreement among raft nodes before linearized reading' (duration: 136.953007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:13.817701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.808648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:10:13.817951Z","caller":"traceutil/trace.go:171","msg":"trace[1864070162] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"543.542298ms","start":"2026-05-08T01:10:13.274374Z","end":"2026-05-08T01:10:13.817917Z","steps":["trace[1864070162] 'process raft request' (duration: 285.409013ms)","trace[1864070162] 'compare' (duration: 257.644034ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:13.818107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.274348Z","time spent":"543.66914ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:10:14.241068Z","caller":"traceutil/trace.go:171","msg":"trace[1489676369] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1507; }","duration":"762.722654ms","start":"2026-05-08T01:10:13.478323Z","end":"2026-05-08T01:10:14.241045Z","steps":["trace[1489676369] 'read index received' (duration: 81.474992ms)","trace[1489676369] 'applied index is now lower than readState.Index' (duration: 681.246602ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:14.241098Z","caller":"traceutil/trace.go:171","msg":"trace[1676079417] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"965.849275ms","start":"2026-05-08T01:10:13.275212Z","end":"2026-05-08T01:10:14.241061Z","steps":["trace[1676079417] 'process raft request' (duration: 938.445925ms)","trace[1676079417] 'compare' (duration: 27.219746ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:14.241171Z","caller":"traceutil/trace.go:171","msg":"trace[1235812251] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"962.721322ms","start":"2026-05-08T01:10:13.278441Z","end":"2026-05-08T01:10:14.241162Z","steps":["trace[1235812251] 'process raft request' (duration: 962.563919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:14.241374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.27519Z","time spent":"966.101902ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:14.241497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.278431Z","time spent":"962.966848ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:14.2418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.257517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-08T01:10:14.241883Z","caller":"traceutil/trace.go:171","msg":"trace[2093733380] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1447; }","duration":"763.609435ms","start":"2026-05-08T01:10:13.478256Z","end":"2026-05-08T01:10:14.241865Z","steps":["trace[2093733380] 'agreement among raft nodes before linearized reading' (duration: 762.926419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:14.242078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.478223Z","time spent":"763.842791ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-08T01:10:14.57199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.605502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:10:14.572209Z","caller":"traceutil/trace.go:171","msg":"trace[95989303] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1510; }","duration":"330.965405ms","start":"2026-05-08T01:10:14.241225Z","end":"2026-05-08T01:10:14.572191Z","steps":["trace[95989303] 'read index received' (duration: 165.954444ms)","trace[95989303] 'applied index is now lower than readState.Index' (duration: 165.010091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:14.572331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.099775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T01:10:14.572416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.532703ms","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-05-08T01:10:14.572446Z","caller":"traceutil/trace.go:171","msg":"trace[888983693] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1449; }","duration":"862.590705ms","start":"2026-05-08T01:10:13.709846Z","end":"2026-05-08T01:10:14.572436Z","steps":["trace[888983693] 'agreement among raft nodes before linearized reading' (duration: 862.542274ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:14.572489Z","caller":"traceutil/trace.go:171","msg":"trace[1211872686] transaction","detail":"{read_only:false; response_revision:1448; number_of_response:1; }","duration":"955.632136ms","start":"2026-05-08T01:10:13.616812Z","end":"2026-05-08T01:10:14.572444Z","steps":["trace[1211872686] 'process raft request' (duration: 790.447881ms)","trace[1211872686] 'compare' (duration: 164.313605ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:14.572514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.70983Z","time spent":"862.674056ms","remote":"127.0.0.1:52706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-08T01:10:14.572536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.755534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:14.572591Z","caller":"traceutil/trace.go:171","msg":"trace[902875609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1449; }","duration":"801.839626ms","start":"2026-05-08T01:10:13.770733Z","end":"2026-05-08T01:10:14.572573Z","steps":["trace[902875609] 'agreement among raft nodes before linearized reading' (duration: 801.779845ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:14.572635Z","caller":"traceutil/trace.go:171","msg":"trace[1391434519] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"949.80357ms","start":"2026-05-08T01:10:13.622823Z","end":"2026-05-08T01:10:14.572626Z","steps":["trace[1391434519] 'process raft request' (duration: 949.264188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:14.57264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.770714Z","time spent":"801.918178ms","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T01:10:14.572688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.622816Z","time spent":"949.845681ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:14.572738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.566845ms","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-05-08T01:10:14.572771Z","caller":"traceutil/trace.go:171","msg":"trace[1196120807] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1449; }","duration":"931.604975ms","start":"2026-05-08T01:10:13.641156Z","end":"2026-05-08T01:10:14.572761Z","steps":["trace[1196120807] 'agreement among raft nodes before linearized reading' (duration: 931.547504ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:14.572409Z","caller":"traceutil/trace.go:171","msg":"trace[1629409195] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1449; }","duration":"336.230458ms","start":"2026-05-08T01:10:14.236161Z","end":"2026-05-08T01:10:14.572391Z","steps":["trace[1629409195] 'agreement among raft nodes before linearized reading' (duration: 336.069974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:14.572808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.641082Z","time spent":"931.717328ms","remote":"127.0.0.1:58722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-08T01:10:14.57284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:14.236135Z","time spent":"336.691029ms","remote":"127.0.0.1:52914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-08T01:10:14.572592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.616787Z","time spent":"955.734459ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:14.572424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.222756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T01:10:14.572991Z","caller":"traceutil/trace.go:171","msg":"trace[177883499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1449; }","duration":"829.83334ms","start":"2026-05-08T01:10:13.743148Z","end":"2026-05-08T01:10:14.572981Z","steps":["trace[177883499] 'agreement among raft nodes before linearized reading' (duration: 829.107263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:14.573031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.743118Z","time spent":"829.904212ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T01:10:14.57237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017004619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-08T01:10:14.573087Z","caller":"traceutil/trace.go:171","msg":"trace[1415578899] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1449; }","duration":"1.017758667s","start":"2026-05-08T01:10:13.555314Z","end":"2026-05-08T01:10:14.573072Z","steps":["trace[1415578899] 'agreement among raft nodes before linearized reading' (duration: 1.016927417s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:14.573131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:13.555259Z","time spent":"1.017860309s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-08T01:10:14.863061Z","caller":"traceutil/trace.go:171","msg":"trace[1467466677] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"289.20008ms","start":"2026-05-08T01:10:14.573836Z","end":"2026-05-08T01:10:14.863036Z","steps":["trace[1467466677] 'read index received' (duration: 289.118678ms)","trace[1467466677] 'applied index is now lower than readState.Index' (duration: 79.892µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:14.863206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.362664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:14.863245Z","caller":"traceutil/trace.go:171","msg":"trace[729714094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1450; }","duration":"289.458827ms","start":"2026-05-08T01:10:14.573776Z","end":"2026-05-08T01:10:14.863235Z","steps":["trace[729714094] 'agreement among raft nodes before linearized reading' (duration: 289.356434ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:14.863274Z","caller":"traceutil/trace.go:171","msg":"trace[1482134671] transaction","detail":"{read_only:false; response_revision:1450; number_of_response:1; }","duration":"616.8984ms","start":"2026-05-08T01:10:14.24627Z","end":"2026-05-08T01:10:14.863168Z","steps":["trace[1482134671] 'process raft request' (duration: 616.619174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:14.863505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:14.246253Z","time spent":"617.124765ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:15.318008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.609036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:10:15.318339Z","caller":"traceutil/trace.go:171","msg":"trace[411413725] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1513; }","duration":"446.801849ms","start":"2026-05-08T01:10:14.871513Z","end":"2026-05-08T01:10:15.318314Z","steps":["trace[411413725] 'read index received' (duration: 247.710182ms)","trace[411413725] 'applied index is now lower than readState.Index' (duration: 199.089967ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:15.318451Z","caller":"traceutil/trace.go:171","msg":"trace[1821494420] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"739.690406ms","start":"2026-05-08T01:10:14.578743Z","end":"2026-05-08T01:10:15.318433Z","steps":["trace[1821494420] 'process raft request' (duration: 540.529327ms)","trace[1821494420] 'compare' (duration: 198.322899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:15.318538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:14.578723Z","time spent":"739.768527ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:10:15.318674Z","caller":"traceutil/trace.go:171","msg":"trace[1386333289] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"739.701706ms","start":"2026-05-08T01:10:14.578937Z","end":"2026-05-08T01:10:15.318639Z","steps":["trace[1386333289] 'process raft request' (duration: 739.202404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:15.318833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:14.578923Z","time spent":"739.824809ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:15.319049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.515296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:15.319125Z","caller":"traceutil/trace.go:171","msg":"trace[634749588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1452; }","duration":"447.634449ms","start":"2026-05-08T01:10:14.871473Z","end":"2026-05-08T01:10:15.319108Z","steps":["trace[634749588] 'agreement among raft nodes before linearized reading' (duration: 447.24997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:15.319327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:14.871454Z","time spent":"447.786432ms","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T01:10:15.818966Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741940762568839,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T01:10:15.856756Z","caller":"traceutil/trace.go:171","msg":"trace[492845406] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"582.494917ms","start":"2026-05-08T01:10:15.274233Z","end":"2026-05-08T01:10:15.856728Z","steps":["trace[492845406] 'process raft request' (duration: 566.681638ms)","trace[492845406] 'compare' (duration: 15.376359ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:15.856876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:15.274203Z","time spent":"582.607689ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:15.856931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.343468ms","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:508"} {"level":"info","ts":"2026-05-08T01:10:15.857198Z","caller":"traceutil/trace.go:171","msg":"trace[2001361955] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1453; }","duration":"763.649705ms","start":"2026-05-08T01:10:15.09353Z","end":"2026-05-08T01:10:15.857179Z","steps":["trace[2001361955] 'agreement among raft nodes before linearized reading' (duration: 763.218355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:15.857188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.942493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-08T01:10:15.857254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:15.093511Z","time spent":"763.730697ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-08T01:10:15.857289Z","caller":"traceutil/trace.go:171","msg":"trace[850187002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1453; }","duration":"944.084607ms","start":"2026-05-08T01:10:14.913193Z","end":"2026-05-08T01:10:15.857277Z","steps":["trace[850187002] 'agreement among raft nodes before linearized reading' (duration: 943.741439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:15.857378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:14.913173Z","time spent":"944.187639ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T01:10:15.856904Z","caller":"traceutil/trace.go:171","msg":"trace[2078885787] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1515; }","duration":"538.49472ms","start":"2026-05-08T01:10:15.31839Z","end":"2026-05-08T01:10:15.856884Z","steps":["trace[2078885787] 'read index received' (duration: 522.534147ms)","trace[2078885787] 'applied index is now lower than readState.Index' (duration: 15.600304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:15.858018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.868727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T01:10:15.858068Z","caller":"traceutil/trace.go:171","msg":"trace[577264156] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:1453; }","duration":"394.949349ms","start":"2026-05-08T01:10:15.463107Z","end":"2026-05-08T01:10:15.858056Z","steps":["trace[577264156] 'agreement among raft nodes before linearized reading' (duration: 394.811246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:15.858553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.961108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:15.8586Z","caller":"traceutil/trace.go:171","msg":"trace[575085087] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1453; }","duration":"254.0461ms","start":"2026-05-08T01:10:15.60454Z","end":"2026-05-08T01:10:15.858587Z","steps":["trace[575085087] 'agreement among raft nodes before linearized reading' (duration: 253.981538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:15.859212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:15.463088Z","time spent":"396.107877ms","remote":"127.0.0.1:52538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.233\" "} {"level":"warn","ts":"2026-05-08T01:10:21.098519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.879183ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-08T01:10:21.098639Z","caller":"traceutil/trace.go:171","msg":"trace[774178367] linearizableReadLoop","detail":"{readStateIndex:1542; appliedIndex:1541; }","duration":"247.15913ms","start":"2026-05-08T01:10:20.851442Z","end":"2026-05-08T01:10:21.098601Z","steps":["trace[774178367] 'read index received' (duration: 69.892µs)","trace[774178367] 'applied index is now lower than readState.Index' (duration: 247.088038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:21.098799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.329183ms","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":"warn","ts":"2026-05-08T01:10:21.098843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.918034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-08T01:10:21.098873Z","caller":"traceutil/trace.go:171","msg":"trace[989136969] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1477; }","duration":"213.981875ms","start":"2026-05-08T01:10:20.884881Z","end":"2026-05-08T01:10:21.098862Z","steps":["trace[989136969] 'agreement among raft nodes before linearized reading' (duration: 213.837721ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:21.09888Z","caller":"traceutil/trace.go:171","msg":"trace[1119636915] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1477; }","duration":"247.478807ms","start":"2026-05-08T01:10:20.851381Z","end":"2026-05-08T01:10:21.09886Z","steps":["trace[1119636915] 'agreement among raft nodes before linearized reading' (duration: 247.313603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:21.098865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.242003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-05-08T01:10:21.098885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.643947ms","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:508"} {"level":"info","ts":"2026-05-08T01:10:21.098946Z","caller":"traceutil/trace.go:171","msg":"trace[1151739548] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1477; }","duration":"174.735968ms","start":"2026-05-08T01:10:20.924192Z","end":"2026-05-08T01:10:21.098928Z","steps":["trace[1151739548] 'agreement among raft nodes before linearized reading' (duration: 174.544764ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:21.098946Z","caller":"traceutil/trace.go:171","msg":"trace[1655607404] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1477; }","duration":"107.322755ms","start":"2026-05-08T01:10:20.991599Z","end":"2026-05-08T01:10:21.098922Z","steps":["trace[1655607404] 'agreement among raft nodes before linearized reading' (duration: 107.12029ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:21.225705Z","caller":"traceutil/trace.go:171","msg":"trace[108423098] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"123.537764ms","start":"2026-05-08T01:10:21.102149Z","end":"2026-05-08T01:10:21.225687Z","steps":["trace[108423098] 'process raft request' (duration: 123.314059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:21.656697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.689732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:10:21.656835Z","caller":"traceutil/trace.go:171","msg":"trace[1105097100] linearizableReadLoop","detail":"{readStateIndex:1545; appliedIndex:1543; }","duration":"316.755313ms","start":"2026-05-08T01:10:21.340066Z","end":"2026-05-08T01:10:21.656821Z","steps":["trace[1105097100] 'read index received' (duration: 95.222µs)","trace[1105097100] 'applied index is now lower than readState.Index' (duration: 316.659491ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:21.65689Z","caller":"traceutil/trace.go:171","msg":"trace[2031118233] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"553.760016ms","start":"2026-05-08T01:10:21.103113Z","end":"2026-05-08T01:10:21.656873Z","steps":["trace[2031118233] 'process raft request' (duration: 553.665633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:21.656975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.910688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T01:10:21.656943Z","caller":"traceutil/trace.go:171","msg":"trace[1740022998] transaction","detail":"{read_only:false; response_revision:1479; number_of_response:1; }","duration":"554.064573ms","start":"2026-05-08T01:10:21.102794Z","end":"2026-05-08T01:10:21.656859Z","steps":["trace[1740022998] 'process raft request' (duration: 207.131065ms)","trace[1740022998] 'compare' (duration: 346.557439ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:21.656981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:21.103101Z","time spent":"553.832488ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:10:21.657006Z","caller":"traceutil/trace.go:171","msg":"trace[734150626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1480; }","duration":"316.978248ms","start":"2026-05-08T01:10:21.34002Z","end":"2026-05-08T01:10:21.656999Z","steps":["trace[734150626] 'agreement among raft nodes before linearized reading' (duration: 316.841565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:21.657124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:21.10277Z","time spent":"554.272178ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:21.657279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:21.339993Z","time spent":"317.207674ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T01:10:22.140385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.448472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T01:10:22.140501Z","caller":"traceutil/trace.go:171","msg":"trace[2099969969] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"195.065123ms","start":"2026-05-08T01:10:21.945414Z","end":"2026-05-08T01:10:22.140479Z","steps":["trace[2099969969] 'process raft request' (duration: 28.909664ms)","trace[2099969969] 'compare' (duration: 165.33175ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:22.494546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.033349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-08T01:10:22.494616Z","caller":"traceutil/trace.go:171","msg":"trace[201009399] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1484; }","duration":"134.142301ms","start":"2026-05-08T01:10:22.360456Z","end":"2026-05-08T01:10:22.494598Z","steps":["trace[201009399] 'range keys from in-memory index tree' (duration: 133.886555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:22.494834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.954838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-08T01:10:22.494911Z","caller":"traceutil/trace.go:171","msg":"trace[1364733598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1484; }","duration":"218.06652ms","start":"2026-05-08T01:10:22.276825Z","end":"2026-05-08T01:10:22.494892Z","steps":["trace[1364733598] 'range keys from in-memory index tree' (duration: 217.798224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:22.49495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.222568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-08T01:10:22.495726Z","caller":"traceutil/trace.go:171","msg":"trace[185821802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1484; }","duration":"220.050917ms","start":"2026-05-08T01:10:22.275659Z","end":"2026-05-08T01:10:22.49571Z","steps":["trace[185821802] 'range keys from in-memory index tree' (duration: 219.050593ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:22.679678Z","caller":"traceutil/trace.go:171","msg":"trace[967428909] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"181.65006ms","start":"2026-05-08T01:10:22.498008Z","end":"2026-05-08T01:10:22.679658Z","steps":["trace[967428909] 'process raft request' (duration: 181.526057ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:22.902547Z","caller":"traceutil/trace.go:171","msg":"trace[217288310] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1549; }","duration":"393.617908ms","start":"2026-05-08T01:10:22.508906Z","end":"2026-05-08T01:10:22.902524Z","steps":["trace[217288310] 'read index received' (duration: 170.725875ms)","trace[217288310] 'applied index is now lower than readState.Index' (duration: 222.890833ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:22.902802Z","caller":"traceutil/trace.go:171","msg":"trace[1803229940] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"403.916258ms","start":"2026-05-08T01:10:22.498872Z","end":"2026-05-08T01:10:22.902788Z","steps":["trace[1803229940] 'process raft request' (duration: 375.592457ms)","trace[1803229940] 'compare' (duration: 27.552773ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:22.902908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:22.49885Z","time spent":"403.9906ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:10:22.903087Z","caller":"traceutil/trace.go:171","msg":"trace[1207620691] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"403.794976ms","start":"2026-05-08T01:10:22.499283Z","end":"2026-05-08T01:10:22.903078Z","steps":["trace[1207620691] 'process raft request' (duration: 403.177441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:22.903141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:22.499276Z","time spent":"403.837587ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:22.903274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.360925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T01:10:22.903329Z","caller":"traceutil/trace.go:171","msg":"trace[808625637] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1487; }","duration":"394.402746ms","start":"2026-05-08T01:10:22.508902Z","end":"2026-05-08T01:10:22.903305Z","steps":["trace[808625637] 'agreement among raft nodes before linearized reading' (duration: 394.340805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:22.903365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:22.508895Z","time spent":"394.462047ms","remote":"127.0.0.1:58800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-08T01:10:22.903506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.454966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:22.903536Z","caller":"traceutil/trace.go:171","msg":"trace[1898492906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1487; }","duration":"133.526067ms","start":"2026-05-08T01:10:22.770003Z","end":"2026-05-08T01:10:22.903529Z","steps":["trace[1898492906] 'agreement among raft nodes before linearized reading' (duration: 133.485656ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:26.912855Z","caller":"traceutil/trace.go:171","msg":"trace[1806643842] linearizableReadLoop","detail":"{readStateIndex:1571; appliedIndex:1570; }","duration":"133.636949ms","start":"2026-05-08T01:10:26.779195Z","end":"2026-05-08T01:10:26.912831Z","steps":["trace[1806643842] 'read index received' (duration: 113.678553ms)","trace[1806643842] 'applied index is now lower than readState.Index' (duration: 19.957126ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:26.913007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.793712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T01:10:26.913039Z","caller":"traceutil/trace.go:171","msg":"trace[859020714] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1505; }","duration":"133.886154ms","start":"2026-05-08T01:10:26.779143Z","end":"2026-05-08T01:10:26.91303Z","steps":["trace[859020714] 'agreement among raft nodes before linearized reading' (duration: 133.794562ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:26.913286Z","caller":"traceutil/trace.go:171","msg":"trace[145919398] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"213.480793ms","start":"2026-05-08T01:10:26.699648Z","end":"2026-05-08T01:10:26.913129Z","steps":["trace[145919398] 'process raft request' (duration: 193.313292ms)","trace[145919398] 'compare' (duration: 19.758981ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:28.231724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581741940762568970,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T01:10:28.574702Z","caller":"traceutil/trace.go:171","msg":"trace[299812151] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"891.274104ms","start":"2026-05-08T01:10:27.683403Z","end":"2026-05-08T01:10:28.574677Z","steps":["trace[299812151] 'process raft request' (duration: 891.09746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:28.574842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:27.68338Z","time spent":"891.389747ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T01:10:28.771042Z","caller":"traceutil/trace.go:171","msg":"trace[1035215718] linearizableReadLoop","detail":"{readStateIndex:1574; appliedIndex:1572; }","duration":"1.039625647s","start":"2026-05-08T01:10:27.731392Z","end":"2026-05-08T01:10:28.771018Z","steps":["trace[1035215718] 'read index received' (duration: 843.13919ms)","trace[1035215718] 'applied index is now lower than readState.Index' (duration: 196.485386ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:28.771383Z","caller":"traceutil/trace.go:171","msg":"trace[583052160] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"834.900019ms","start":"2026-05-08T01:10:27.936467Z","end":"2026-05-08T01:10:28.771367Z","steps":["trace[583052160] 'process raft request' (duration: 780.373996ms)","trace[583052160] 'compare' (duration: 54.057972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T01:10:28.771494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:27.936445Z","time spent":"834.97376ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T01:10:28.771606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.439846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-08T01:10:28.771587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.277934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:28.771669Z","caller":"traceutil/trace.go:171","msg":"trace[1106663150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1508; }","duration":"609.548568ms","start":"2026-05-08T01:10:28.1621Z","end":"2026-05-08T01:10:28.771649Z","steps":["trace[1106663150] 'agreement among raft nodes before linearized reading' (duration: 609.372074ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:28.771678Z","caller":"traceutil/trace.go:171","msg":"trace[20716253] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1508; }","duration":"852.396687ms","start":"2026-05-08T01:10:27.919265Z","end":"2026-05-08T01:10:28.771662Z","steps":["trace[20716253] 'agreement among raft nodes before linearized reading' (duration: 852.221632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:28.771709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:28.162079Z","time spent":"609.621269ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T01:10:28.771725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:27.919215Z","time spent":"852.496469ms","remote":"127.0.0.1:52762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-08T01:10:28.771879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.382726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-05-08T01:10:28.771878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.400553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T01:10:28.771983Z","caller":"traceutil/trace.go:171","msg":"trace[662736371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1508; }","duration":"779.593757ms","start":"2026-05-08T01:10:27.992365Z","end":"2026-05-08T01:10:28.771959Z","steps":["trace[662736371] 'agreement among raft nodes before linearized reading' (duration: 779.206298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:28.772031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:27.99235Z","time spent":"779.672539ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T01:10:28.771921Z","caller":"traceutil/trace.go:171","msg":"trace[958428460] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1508; }","duration":"864.452108ms","start":"2026-05-08T01:10:27.907454Z","end":"2026-05-08T01:10:28.771906Z","steps":["trace[958428460] 'agreement among raft nodes before linearized reading' (duration: 864.296585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:28.772094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:27.907438Z","time spent":"864.648512ms","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-08T01:10:28.772043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00507278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:28.7722Z","caller":"traceutil/trace.go:171","msg":"trace[822564725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1508; }","duration":"1.005254235s","start":"2026-05-08T01:10:27.766934Z","end":"2026-05-08T01:10:28.772188Z","steps":["trace[822564725] 'agreement among raft nodes before linearized reading' (duration: 1.004503207s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:28.772226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:27.76692Z","time spent":"1.005299986s","remote":"127.0.0.1:52506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T01:10:28.772251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040841546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T01:10:28.772372Z","caller":"traceutil/trace.go:171","msg":"trace[1621709541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1508; }","duration":"1.041000979s","start":"2026-05-08T01:10:27.731325Z","end":"2026-05-08T01:10:28.772326Z","steps":["trace[1621709541] 'agreement among raft nodes before linearized reading' (duration: 1.040782174s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:28.772411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T01:10:27.7313Z","time spent":"1.041102002s","remote":"127.0.0.1:52744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T01:10:29.004995Z","caller":"traceutil/trace.go:171","msg":"trace[1603752562] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"228.202896ms","start":"2026-05-08T01:10:28.776765Z","end":"2026-05-08T01:10:29.004968Z","steps":["trace[1603752562] 'process raft request' (duration: 220.468996ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:29.076362Z","caller":"traceutil/trace.go:171","msg":"trace[365080283] linearizableReadLoop","detail":"{readStateIndex:1577; appliedIndex:1574; }","duration":"294.554196ms","start":"2026-05-08T01:10:28.781789Z","end":"2026-05-08T01:10:29.076343Z","steps":["trace[365080283] 'read index received' (duration: 215.532191ms)","trace[365080283] 'applied index is now lower than readState.Index' (duration: 79.021175ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T01:10:29.076424Z","caller":"traceutil/trace.go:171","msg":"trace[838960606] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"299.561093ms","start":"2026-05-08T01:10:28.776828Z","end":"2026-05-08T01:10:29.076389Z","steps":["trace[838960606] 'process raft request' (duration: 299.348488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:29.076487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.683128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T01:10:29.076511Z","caller":"traceutil/trace.go:171","msg":"trace[395351339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1512; }","duration":"294.737489ms","start":"2026-05-08T01:10:28.781767Z","end":"2026-05-08T01:10:29.076504Z","steps":["trace[395351339] 'agreement among raft nodes before linearized reading' (duration: 294.678758ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:29.076516Z","caller":"traceutil/trace.go:171","msg":"trace[1706954083] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"299.135122ms","start":"2026-05-08T01:10:28.777371Z","end":"2026-05-08T01:10:29.076506Z","steps":["trace[1706954083] 'process raft request' (duration: 298.926658ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T01:10:29.076471Z","caller":"traceutil/trace.go:171","msg":"trace[698501425] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"294.471163ms","start":"2026-05-08T01:10:28.781979Z","end":"2026-05-08T01:10:29.07645Z","steps":["trace[698501425] 'process raft request' (duration: 294.33982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:29.076941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.754179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T01:10:29.077038Z","caller":"traceutil/trace.go:171","msg":"trace[246067486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1512; }","duration":"159.910032ms","start":"2026-05-08T01:10:28.917113Z","end":"2026-05-08T01:10:29.077023Z","steps":["trace[246067486] 'agreement among raft nodes before linearized reading' (duration: 159.587995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T01:10:29.077127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.902006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T01:10:29.077154Z","caller":"traceutil/trace.go:171","msg":"trace[503489551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1512; }","duration":"121.974007ms","start":"2026-05-08T01:10:28.955173Z","end":"2026-05-08T01:10:29.077147Z","steps":["trace[503489551] 'agreement among raft nodes before linearized reading' (duration: 121.771893ms)"],"step_count":1}