{"level":"warn","ts":"2026-04-09T02:02:38.739446Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:02:38.739545Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-04-09T02:02:38.739676Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T02:02:38.739696Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-09T02:02:38.739721Z","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-04-09T02:02:38.740527Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-09T02:02:38.740618Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-04-09T02:02:38.746804Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.887554ms"} {"level":"info","ts":"2026-04-09T02:02:38.75765Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-09T02:02:38.757727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T02:02:38.757748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-09T02:02:38.757762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T02:02:38.757772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-09T02:02:38.757824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-09T02:02:38.763776Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T02:02:38.765031Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T02:02:38.766973Z","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-04-09T02:02:38.770238Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T02:02:38.770344Z","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-04-09T02:02:38.770423Z","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-04-09T02:02:38.77044Z","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-04-09T02:02:38.770463Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T02:02:38.772827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-09T02:02:38.773706Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-09T02:02:38.775931Z","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-04-09T02:02:38.775968Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-09T02:02:38.776061Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-09T02:02:38.776577Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T02:02:38.776724Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T02:02:38.85799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T02:02:38.858069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T02:02:38.858101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-09T02:02:38.858121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-09T02:02:38.858132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-09T02:02:38.858148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-09T02:02:38.858163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-09T02:02:38.858902Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:02:38.859748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:02:38.859759Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T02:02:38.859898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T02:02:38.860869Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T02:02:38.860886Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T02:02:38.862822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-09T02:02:38.86295Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:02:38.863041Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:02:38.863067Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T02:02:38.863905Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T02:03:13.287222Z","caller":"traceutil/trace.go:171","msg":"trace[973019682] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"368.757336ms","start":"2026-04-09T02:03:12.918442Z","end":"2026-04-09T02:03:13.2872Z","steps":["trace[973019682] 'read index received' (duration: 368.553901ms)","trace[973019682] 'applied index is now lower than readState.Index' (duration: 199.995µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:13.287403Z","caller":"traceutil/trace.go:171","msg":"trace[485996724] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"403.408801ms","start":"2026-04-09T02:03:12.883943Z","end":"2026-04-09T02:03:13.287352Z","steps":["trace[485996724] 'process raft request' (duration: 403.123784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:13.2875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.894623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-09T02:03:13.287503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.999832ms","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-04-09T02:03:13.287581Z","caller":"traceutil/trace.go:171","msg":"trace[1966486483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"249.021656ms","start":"2026-04-09T02:03:13.038545Z","end":"2026-04-09T02:03:13.287567Z","steps":["trace[1966486483] 'agreement among raft nodes before linearized reading' (duration: 248.880863ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:13.287639Z","caller":"traceutil/trace.go:171","msg":"trace[843451981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:483; }","duration":"369.207517ms","start":"2026-04-09T02:03:12.918392Z","end":"2026-04-09T02:03:13.2876Z","steps":["trace[843451981] 'agreement among raft nodes before linearized reading' (duration: 368.945881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:13.287682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:12.918374Z","time spent":"369.29463ms","remote":"127.0.0.1:46994","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-04-09T02:03:13.287774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.140816ms","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-04-09T02:03:13.287805Z","caller":"traceutil/trace.go:171","msg":"trace[1918777071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"369.234069ms","start":"2026-04-09T02:03:12.918561Z","end":"2026-04-09T02:03:13.287795Z","steps":["trace[1918777071] 'agreement among raft nodes before linearized reading' (duration: 368.806698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:13.287832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:12.918554Z","time spent":"369.27111ms","remote":"127.0.0.1:46994","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-04-09T02:03:13.288945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:12.883926Z","time spent":"403.608466ms","remote":"127.0.0.1:46994","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-04-09T02:03:16.104078Z","caller":"traceutil/trace.go:171","msg":"trace[359181811] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"211.26345ms","start":"2026-04-09T02:03:15.892786Z","end":"2026-04-09T02:03:16.10405Z","steps":["trace[359181811] 'process raft request' (duration: 211.170218ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.104317Z","caller":"traceutil/trace.go:171","msg":"trace[2018330218] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"215.888562ms","start":"2026-04-09T02:03:15.888392Z","end":"2026-04-09T02:03:16.104281Z","steps":["trace[2018330218] 'process raft request' (duration: 214.917136ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.111018Z","caller":"traceutil/trace.go:171","msg":"trace[1705705266] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"212.568353ms","start":"2026-04-09T02:03:15.898418Z","end":"2026-04-09T02:03:16.110986Z","steps":["trace[1705705266] 'process raft request' (duration: 212.271736ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.111838Z","caller":"traceutil/trace.go:171","msg":"trace[1113042335] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"209.440453ms","start":"2026-04-09T02:03:15.90237Z","end":"2026-04-09T02:03:16.111811Z","steps":["trace[1113042335] 'process raft request' (duration: 208.422406ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.11196Z","caller":"traceutil/trace.go:171","msg":"trace[1681998075] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"207.585275ms","start":"2026-04-09T02:03:15.904355Z","end":"2026-04-09T02:03:16.11194Z","steps":["trace[1681998075] 'process raft request' (duration: 206.477775ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.11199Z","caller":"traceutil/trace.go:171","msg":"trace[170542341] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"205.472339ms","start":"2026-04-09T02:03:15.906489Z","end":"2026-04-09T02:03:16.111961Z","steps":["trace[170542341] 'process raft request' (duration: 205.285854ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.113104Z","caller":"traceutil/trace.go:171","msg":"trace[1153769610] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"202.431879ms","start":"2026-04-09T02:03:15.910658Z","end":"2026-04-09T02:03:16.11309Z","steps":["trace[1153769610] 'process raft request' (duration: 201.71155ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.113303Z","caller":"traceutil/trace.go:171","msg":"trace[799287620] transaction","detail":"{read_only:false; number_of_response:1; response_revision:533; }","duration":"206.749783ms","start":"2026-04-09T02:03:15.906539Z","end":"2026-04-09T02:03:16.113288Z","steps":["trace[799287620] 'process raft request' (duration: 205.373007ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.113309Z","caller":"traceutil/trace.go:171","msg":"trace[606337759] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"176.979344ms","start":"2026-04-09T02:03:15.936322Z","end":"2026-04-09T02:03:16.113301Z","steps":["trace[606337759] 'process raft request' (duration: 176.138922ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.115678Z","caller":"traceutil/trace.go:171","msg":"trace[371945894] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"206.442285ms","start":"2026-04-09T02:03:15.909221Z","end":"2026-04-09T02:03:16.115663Z","steps":["trace[371945894] 'process raft request' (duration: 203.070746ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.117889Z","caller":"traceutil/trace.go:171","msg":"trace[879805460] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"150.976166ms","start":"2026-04-09T02:03:15.966897Z","end":"2026-04-09T02:03:16.117873Z","steps":["trace[879805460] 'process raft request' (duration: 145.674727ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.12351Z","caller":"traceutil/trace.go:171","msg":"trace[976021675] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"134.980487ms","start":"2026-04-09T02:03:15.988092Z","end":"2026-04-09T02:03:16.123072Z","steps":["trace[976021675] 'process raft request' (duration: 124.629476ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.124138Z","caller":"traceutil/trace.go:171","msg":"trace[836259788] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"187.502579ms","start":"2026-04-09T02:03:15.93644Z","end":"2026-04-09T02:03:16.123943Z","steps":["trace[836259788] 'process raft request' (duration: 176.06983ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:16.703574Z","caller":"traceutil/trace.go:171","msg":"trace[759425726] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"487.231201ms","start":"2026-04-09T02:03:16.216308Z","end":"2026-04-09T02:03:16.703539Z","steps":["trace[759425726] 'process raft request' (duration: 484.92643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:16.703786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:16.216273Z","time spent":"487.419536ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-09T02:03:16.709091Z","caller":"traceutil/trace.go:171","msg":"trace[371192286] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:578; }","duration":"475.716181ms","start":"2026-04-09T02:03:16.23336Z","end":"2026-04-09T02:03:16.709076Z","steps":["trace[371192286] 'read index received' (duration: 467.946607ms)","trace[371192286] 'applied index is now lower than readState.Index' (duration: 7.768684ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:16.709282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.905735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:30389"} {"level":"info","ts":"2026-04-09T02:03:16.709323Z","caller":"traceutil/trace.go:171","msg":"trace[1745859304] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:557; }","duration":"475.986218ms","start":"2026-04-09T02:03:16.233325Z","end":"2026-04-09T02:03:16.709311Z","steps":["trace[1745859304] 'agreement among raft nodes before linearized reading' (duration: 475.810293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:16.709355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:16.233311Z","time spent":"476.036459ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":30412,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-09T02:03:16.709652Z","caller":"traceutil/trace.go:171","msg":"trace[913198908] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"481.810269ms","start":"2026-04-09T02:03:16.227799Z","end":"2026-04-09T02:03:16.709609Z","steps":["trace[913198908] 'process raft request' (duration: 480.920356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:16.710199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:16.227762Z","time spent":"482.189429ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:16.710257Z","caller":"traceutil/trace.go:171","msg":"trace[865243376] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"490.427834ms","start":"2026-04-09T02:03:16.219818Z","end":"2026-04-09T02:03:16.710246Z","steps":["trace[865243376] 'process raft request' (duration: 488.692609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:16.710407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:16.21978Z","time spent":"490.565967ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:16.710576Z","caller":"traceutil/trace.go:171","msg":"trace[1047849538] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"484.935771ms","start":"2026-04-09T02:03:16.22562Z","end":"2026-04-09T02:03:16.710555Z","steps":["trace[1047849538] 'process raft request' (duration: 483.046752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:16.710636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:16.225598Z","time spent":"485.007244ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:16.709722Z","caller":"traceutil/trace.go:171","msg":"trace[751692398] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"470.626667ms","start":"2026-04-09T02:03:16.239082Z","end":"2026-04-09T02:03:16.709709Z","steps":["trace[751692398] 'process raft request' (duration: 469.696764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:16.710799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:16.239074Z","time spent":"471.697866ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:16.709758Z","caller":"traceutil/trace.go:171","msg":"trace[261499341] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"441.222119ms","start":"2026-04-09T02:03:16.268528Z","end":"2026-04-09T02:03:16.70975Z","steps":["trace[261499341] 'process raft request' (duration: 440.429578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:16.710896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:16.268519Z","time spent":"442.355088ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:23.255309Z","caller":"traceutil/trace.go:171","msg":"trace[2048772904] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"224.197568ms","start":"2026-04-09T02:03:23.031087Z","end":"2026-04-09T02:03:23.255284Z","steps":["trace[2048772904] 'read index received' (duration: 222.696019ms)","trace[2048772904] 'applied index is now lower than readState.Index' (duration: 1.500409ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:23.255444Z","caller":"traceutil/trace.go:171","msg":"trace[1659362581] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"598.879139ms","start":"2026-04-09T02:03:22.656498Z","end":"2026-04-09T02:03:23.255377Z","steps":["trace[1659362581] 'process raft request' (duration: 597.523663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:23.255708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.491325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x7hrk\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-09T02:03:23.255935Z","caller":"traceutil/trace.go:171","msg":"trace[1664173772] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x7hrk; range_end:; response_count:1; response_revision:623; }","duration":"224.913636ms","start":"2026-04-09T02:03:23.03101Z","end":"2026-04-09T02:03:23.255924Z","steps":["trace[1664173772] 'agreement among raft nodes before linearized reading' (duration: 224.422573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:23.25634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:22.656465Z","time spent":"599.32771ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:23.748437Z","caller":"traceutil/trace.go:171","msg":"trace[112681246] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"472.025314ms","start":"2026-04-09T02:03:23.276384Z","end":"2026-04-09T02:03:23.748409Z","steps":["trace[112681246] 'process raft request' (duration: 471.83524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:23.748615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:23.276339Z","time spent":"472.183949ms","remote":"127.0.0.1:46916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:03:25.194842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409454179s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:03:25.195001Z","caller":"traceutil/trace.go:171","msg":"trace[167625468] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"290.0908ms","start":"2026-04-09T02:03:24.904896Z","end":"2026-04-09T02:03:25.194987Z","steps":["trace[167625468] 'process raft request' (duration: 290.044059ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:25.195094Z","caller":"traceutil/trace.go:171","msg":"trace[1669981694] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.869375007s","start":"2026-04-09T02:03:23.325663Z","end":"2026-04-09T02:03:25.195038Z","steps":["trace[1669981694] 'process raft request' (duration: 459.561878ms)","trace[1669981694] 'compare' (duration: 1.409313375s)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:25.195173Z","caller":"traceutil/trace.go:171","msg":"trace[156273137] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"1.868446513s","start":"2026-04-09T02:03:23.326698Z","end":"2026-04-09T02:03:25.195144Z","steps":["trace[156273137] 'read index received' (duration: 421.585776ms)","trace[156273137] 'applied index is now lower than readState.Index' (duration: 1.446860087s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:25.19528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:23.325635Z","time spent":"1.869559832s","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T02:03:25.19532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.868622807s","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-04-09T02:03:25.195335Z","caller":"traceutil/trace.go:171","msg":"trace[891485668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"1.868665898s","start":"2026-04-09T02:03:23.326664Z","end":"2026-04-09T02:03:25.19533Z","steps":["trace[891485668] 'agreement among raft nodes before linearized reading' (duration: 1.868558145s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:25.195352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:23.326655Z","time spent":"1.868691569s","remote":"127.0.0.1:46994","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-04-09T02:03:25.195404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399284583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:03:25.195457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852917207s","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-04-09T02:03:25.195526Z","caller":"traceutil/trace.go:171","msg":"trace[1709330128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.853040309s","start":"2026-04-09T02:03:23.342464Z","end":"2026-04-09T02:03:25.195504Z","steps":["trace[1709330128] 'agreement among raft nodes before linearized reading' (duration: 1.852796863s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:25.195534Z","caller":"traceutil/trace.go:171","msg":"trace[260353055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.399464268s","start":"2026-04-09T02:03:23.796034Z","end":"2026-04-09T02:03:25.195498Z","steps":["trace[260353055] 'agreement among raft nodes before linearized reading' (duration: 1.399292564s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:25.195646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:23.342414Z","time spent":"1.853181214s","remote":"127.0.0.1:46994","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-04-09T02:03:25.195656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850076043s","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-04-09T02:03:25.195763Z","caller":"traceutil/trace.go:171","msg":"trace[1046580401] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"1.850272948s","start":"2026-04-09T02:03:23.345464Z","end":"2026-04-09T02:03:25.195737Z","steps":["trace[1046580401] 'agreement among raft nodes before linearized reading' (duration: 1.849952699s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:25.195814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:23.345442Z","time spent":"1.850362811s","remote":"127.0.0.1:46994","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-04-09T02:03:25.195688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:23.796009Z","time spent":"1.399665524s","remote":"127.0.0.1:46702","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-04-09T02:03:25.196532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.850868084s","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-04-09T02:03:25.196594Z","caller":"traceutil/trace.go:171","msg":"trace[183776042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"1.850937516s","start":"2026-04-09T02:03:23.345637Z","end":"2026-04-09T02:03:25.196575Z","steps":["trace[183776042] 'agreement among raft nodes before linearized reading' (duration: 1.849667272s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:25.196627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:23.345633Z","time spent":"1.850986967s","remote":"127.0.0.1:46994","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-04-09T02:03:26.205144Z","caller":"traceutil/trace.go:171","msg":"trace[1556457117] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"135.369028ms","start":"2026-04-09T02:03:26.069739Z","end":"2026-04-09T02:03:26.205108Z","steps":["trace[1556457117] 'process raft request' (duration: 130.695066ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:26.569486Z","caller":"traceutil/trace.go:171","msg":"trace[559166469] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"212.84033ms","start":"2026-04-09T02:03:26.356613Z","end":"2026-04-09T02:03:26.569453Z","steps":["trace[559166469] 'process raft request' (duration: 212.665086ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:30.438Z","caller":"traceutil/trace.go:171","msg":"trace[458425101] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"142.083212ms","start":"2026-04-09T02:03:30.295881Z","end":"2026-04-09T02:03:30.437965Z","steps":["trace[458425101] 'process raft request' (duration: 141.819005ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:36.900896Z","caller":"traceutil/trace.go:171","msg":"trace[1128469949] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"105.969508ms","start":"2026-04-09T02:03:36.794881Z","end":"2026-04-09T02:03:36.90085Z","steps":["trace[1128469949] 'read index received' (duration: 105.582778ms)","trace[1128469949] 'applied index is now lower than readState.Index' (duration: 385.54µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:36.901102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.184964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:03:36.90117Z","caller":"traceutil/trace.go:171","msg":"trace[1876150808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"106.278687ms","start":"2026-04-09T02:03:36.794876Z","end":"2026-04-09T02:03:36.901154Z","steps":["trace[1876150808] 'agreement among raft nodes before linearized reading' (duration: 106.134993ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:03:36.901554Z","caller":"traceutil/trace.go:171","msg":"trace[1133471488] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"263.469684ms","start":"2026-04-09T02:03:36.638028Z","end":"2026-04-09T02:03:36.901497Z","steps":["trace[1133471488] 'process raft request' (duration: 262.54799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.809371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.246913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wlpxb\" ","response":"range_response_count:1 size:3963"} {"level":"info","ts":"2026-04-09T02:03:42.809492Z","caller":"traceutil/trace.go:171","msg":"trace[1767439692] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wlpxb; range_end:; response_count:1; response_revision:803; }","duration":"665.457139ms","start":"2026-04-09T02:03:42.144009Z","end":"2026-04-09T02:03:42.809466Z","steps":["trace[1767439692] 'range keys from in-memory index tree' (duration: 664.941775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.809547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:42.143984Z","time spent":"665.552091ms","remote":"127.0.0.1:46916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wlpxb\" "} {"level":"info","ts":"2026-04-09T02:03:42.80965Z","caller":"traceutil/trace.go:171","msg":"trace[1076791916] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"480.65235ms","start":"2026-04-09T02:03:42.328968Z","end":"2026-04-09T02:03:42.809621Z","steps":["trace[1076791916] 'read index received' (duration: 386.302154ms)","trace[1076791916] 'applied index is now lower than readState.Index' (duration: 94.348926ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:03:42.809781Z","caller":"traceutil/trace.go:171","msg":"trace[433085652] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"631.636105ms","start":"2026-04-09T02:03:42.178132Z","end":"2026-04-09T02:03:42.809769Z","steps":["trace[433085652] 'process raft request' (duration: 537.218797ms)","trace[433085652] 'compare' (duration: 93.75525ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:03:42.80987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.523339ms","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-04-09T02:03:42.809904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:42.178113Z","time spent":"631.693485ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T02:03:42.809924Z","caller":"traceutil/trace.go:171","msg":"trace[1085705386] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:804; }","duration":"412.617661ms","start":"2026-04-09T02:03:42.397291Z","end":"2026-04-09T02:03:42.809909Z","steps":["trace[1085705386] 'agreement among raft nodes before linearized reading' (duration: 412.523039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.809958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:42.397277Z","time spent":"412.672724ms","remote":"127.0.0.1:47126","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-04-09T02:03:42.810122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.820128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:03:42.81015Z","caller":"traceutil/trace.go:171","msg":"trace[1819699565] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:804; }","duration":"306.87323ms","start":"2026-04-09T02:03:42.503268Z","end":"2026-04-09T02:03:42.810141Z","steps":["trace[1819699565] 'agreement among raft nodes before linearized reading' (duration: 306.829729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.810188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:42.503252Z","time spent":"306.928011ms","remote":"127.0.0.1:47170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:03:42.810224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.292653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:03:42.810257Z","caller":"traceutil/trace.go:171","msg":"trace[504623249] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:804; }","duration":"389.350863ms","start":"2026-04-09T02:03:42.420896Z","end":"2026-04-09T02:03:42.810247Z","steps":["trace[504623249] 'agreement among raft nodes before linearized reading' (duration: 389.285942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.810285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:42.420885Z","time spent":"389.392755ms","remote":"127.0.0.1:47010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:03:42.811722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.713934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-09T02:03:42.811824Z","caller":"traceutil/trace.go:171","msg":"trace[2028588581] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:804; }","duration":"482.882978ms","start":"2026-04-09T02:03:42.328918Z","end":"2026-04-09T02:03:42.811801Z","steps":["trace[2028588581] 'agreement among raft nodes before linearized reading' (duration: 481.185044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:42.811916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:03:42.328899Z","time spent":"482.97435ms","remote":"127.0.0.1:46728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"info","ts":"2026-04-09T02:03:46.999901Z","caller":"traceutil/trace.go:171","msg":"trace[1877923191] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"111.461952ms","start":"2026-04-09T02:03:46.888366Z","end":"2026-04-09T02:03:46.999828Z","steps":["trace[1877923191] 'process raft request' (duration: 110.193649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:03:47.322326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.608983ms","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-04-09T02:03:47.322442Z","caller":"traceutil/trace.go:171","msg":"trace[767210597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:854; }","duration":"244.805087ms","start":"2026-04-09T02:03:47.077613Z","end":"2026-04-09T02:03:47.322418Z","steps":["trace[767210597] 'range keys from in-memory index tree' (duration: 244.356625ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:01.606245Z","caller":"traceutil/trace.go:171","msg":"trace[1858589557] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"121.495742ms","start":"2026-04-09T02:04:01.484707Z","end":"2026-04-09T02:04:01.606202Z","steps":["trace[1858589557] 'process raft request' (duration: 42.780117ms)","trace[1858589557] 'compare' (duration: 78.495218ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:17.931504Z","caller":"traceutil/trace.go:171","msg":"trace[193930461] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"197.867053ms","start":"2026-04-09T02:04:17.733609Z","end":"2026-04-09T02:04:17.931477Z","steps":["trace[193930461] 'read index received' (duration: 197.726639ms)","trace[193930461] 'applied index is now lower than readState.Index' (duration: 139.374µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:17.931574Z","caller":"traceutil/trace.go:171","msg":"trace[1796081055] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"339.027783ms","start":"2026-04-09T02:04:17.592514Z","end":"2026-04-09T02:04:17.931542Z","steps":["trace[1796081055] 'process raft request' (duration: 338.815218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:17.931684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.048779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:17.931747Z","caller":"traceutil/trace.go:171","msg":"trace[958126724] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1016; }","duration":"198.184192ms","start":"2026-04-09T02:04:17.733554Z","end":"2026-04-09T02:04:17.931738Z","steps":["trace[958126724] 'agreement among raft nodes before linearized reading' (duration: 198.042739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:17.931749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:17.592484Z","time spent":"339.184198ms","remote":"127.0.0.1:46994","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-04-09T02:04:19.353304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110847509s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T02:04:19.353534Z","caller":"traceutil/trace.go:171","msg":"trace[18285383] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"1.421943628s","start":"2026-04-09T02:04:17.931572Z","end":"2026-04-09T02:04:19.353515Z","steps":["trace[18285383] 'read index received' (duration: 310.795719ms)","trace[18285383] 'applied index is now lower than readState.Index' (duration: 1.111146899s)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:19.353661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52301644s","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-04-09T02:04:19.353694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52527586s","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-04-09T02:04:19.353709Z","caller":"traceutil/trace.go:171","msg":"trace[1385840071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.523085502s","start":"2026-04-09T02:04:17.830601Z","end":"2026-04-09T02:04:19.353686Z","steps":["trace[1385840071] 'agreement among raft nodes before linearized reading' (duration: 1.522929338s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:19.353724Z","caller":"traceutil/trace.go:171","msg":"trace[1008615892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"1.525347062s","start":"2026-04-09T02:04:17.828367Z","end":"2026-04-09T02:04:19.353714Z","steps":["trace[1008615892] 'agreement among raft nodes before linearized reading' (duration: 1.525248979s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:19.353753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:17.83059Z","time spent":"1.523155973s","remote":"127.0.0.1:46994","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-04-09T02:04:19.353754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:17.828334Z","time spent":"1.525412802s","remote":"127.0.0.1:46994","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-04-09T02:04:19.353825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521792397s","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-04-09T02:04:19.35383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558903726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:19.35392Z","caller":"traceutil/trace.go:171","msg":"trace[117114796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"1.521912381s","start":"2026-04-09T02:04:17.831983Z","end":"2026-04-09T02:04:19.353895Z","steps":["trace[117114796] 'agreement among raft nodes before linearized reading' (duration: 1.521616174s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:19.353927Z","caller":"traceutil/trace.go:171","msg":"trace[398361619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"1.559041099s","start":"2026-04-09T02:04:17.794862Z","end":"2026-04-09T02:04:19.353903Z","steps":["trace[398361619] 'agreement among raft nodes before linearized reading' (duration: 1.558877285s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:19.353942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539001821s","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-04-09T02:04:19.353973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:17.794846Z","time spent":"1.559119371s","remote":"127.0.0.1:46702","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-04-09T02:04:19.353972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:17.831976Z","time spent":"1.521985843s","remote":"127.0.0.1:46994","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-04-09T02:04:19.354024Z","caller":"traceutil/trace.go:171","msg":"trace[118197419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"1.539111714s","start":"2026-04-09T02:04:17.814891Z","end":"2026-04-09T02:04:19.354003Z","steps":["trace[118197419] 'agreement among raft nodes before linearized reading' (duration: 1.538875338s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:19.354072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:17.814881Z","time spent":"1.539180055s","remote":"127.0.0.1:46994","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-04-09T02:04:19.354386Z","caller":"traceutil/trace.go:171","msg":"trace[951092781] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.073229998s","start":"2026-04-09T02:04:18.281145Z","end":"2026-04-09T02:04:19.354375Z","steps":["trace[951092781] 'process raft request' (duration: 1.072278872s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:19.35449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:18.281121Z","time spent":"1.07329886s","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:04:19.356185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.489589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:19.357496Z","caller":"traceutil/trace.go:171","msg":"trace[1781580173] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1017; }","duration":"357.602661ms","start":"2026-04-09T02:04:18.998629Z","end":"2026-04-09T02:04:19.356232Z","steps":["trace[1781580173] 'agreement among raft nodes before linearized reading' (duration: 355.749603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:19.357581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:18.998608Z","time spent":"358.954447ms","remote":"127.0.0.1:46878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:04:27.706978Z","caller":"traceutil/trace.go:171","msg":"trace[285267620] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"185.560209ms","start":"2026-04-09T02:04:27.521403Z","end":"2026-04-09T02:04:27.706963Z","steps":["trace[285267620] 'process raft request' (duration: 93.713959ms)","trace[285267620] 'compare' (duration: 91.655566ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:27.7069Z","caller":"traceutil/trace.go:171","msg":"trace[2131636248] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"126.40285ms","start":"2026-04-09T02:04:27.580469Z","end":"2026-04-09T02:04:27.706872Z","steps":["trace[2131636248] 'read index received' (duration: 34.56956ms)","trace[2131636248] 'applied index is now lower than readState.Index' (duration: 91.83238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:27.707162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.672058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:04:27.70726Z","caller":"traceutil/trace.go:171","msg":"trace[1076677359] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1048; }","duration":"126.811521ms","start":"2026-04-09T02:04:27.580414Z","end":"2026-04-09T02:04:27.707225Z","steps":["trace[1076677359] 'agreement among raft nodes before linearized reading' (duration: 126.657147ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:39.58821Z","caller":"traceutil/trace.go:171","msg":"trace[1199973659] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"106.228128ms","start":"2026-04-09T02:04:39.481963Z","end":"2026-04-09T02:04:39.588191Z","steps":["trace[1199973659] 'read index received' (duration: 106.099965ms)","trace[1199973659] 'applied index is now lower than readState.Index' (duration: 127.003µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:39.588333Z","caller":"traceutil/trace.go:171","msg":"trace[1808614776] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"111.23194ms","start":"2026-04-09T02:04:39.477057Z","end":"2026-04-09T02:04:39.588289Z","steps":["trace[1808614776] 'process raft request' (duration: 111.001504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:39.588397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.419164ms","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-04-09T02:04:39.588436Z","caller":"traceutil/trace.go:171","msg":"trace[1009288413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1083; }","duration":"106.503826ms","start":"2026-04-09T02:04:39.481921Z","end":"2026-04-09T02:04:39.588425Z","steps":["trace[1009288413] 'agreement among raft nodes before linearized reading' (duration: 106.385672ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:41.833416Z","caller":"traceutil/trace.go:171","msg":"trace[147533078] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"206.380868ms","start":"2026-04-09T02:04:41.627015Z","end":"2026-04-09T02:04:41.833396Z","steps":["trace[147533078] 'read index received' (duration: 206.06789ms)","trace[147533078] 'applied index is now lower than readState.Index' (duration: 311.868µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:41.833506Z","caller":"traceutil/trace.go:171","msg":"trace[1347898804] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"240.088616ms","start":"2026-04-09T02:04:41.593395Z","end":"2026-04-09T02:04:41.833484Z","steps":["trace[1347898804] 'process raft request' (duration: 239.758108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:41.83365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.121274ms","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-04-09T02:04:41.833725Z","caller":"traceutil/trace.go:171","msg":"trace[1428556702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"178.241597ms","start":"2026-04-09T02:04:41.655472Z","end":"2026-04-09T02:04:41.833714Z","steps":["trace[1428556702] 'agreement among raft nodes before linearized reading' (duration: 178.041321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:41.834004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.233579ms","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-04-09T02:04:41.834078Z","caller":"traceutil/trace.go:171","msg":"trace[1496858609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"172.349372ms","start":"2026-04-09T02:04:41.661718Z","end":"2026-04-09T02:04:41.834067Z","steps":["trace[1496858609] 'agreement among raft nodes before linearized reading' (duration: 172.133456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:41.834264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.226011ms","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-04-09T02:04:41.834314Z","caller":"traceutil/trace.go:171","msg":"trace[422848656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"207.293343ms","start":"2026-04-09T02:04:41.627009Z","end":"2026-04-09T02:04:41.834303Z","steps":["trace[422848656] 'agreement among raft nodes before linearized reading' (duration: 206.497971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:42.560483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.278826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:04:42.560524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.147182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-09T02:04:42.560571Z","caller":"traceutil/trace.go:171","msg":"trace[911056093] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1094; }","duration":"321.412399ms","start":"2026-04-09T02:04:42.239139Z","end":"2026-04-09T02:04:42.560552Z","steps":["trace[911056093] 'count revisions from in-memory index tree' (duration: 321.212204ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:42.560596Z","caller":"traceutil/trace.go:171","msg":"trace[684109302] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:1094; }","duration":"228.255934ms","start":"2026-04-09T02:04:42.332318Z","end":"2026-04-09T02:04:42.560574Z","steps":["trace[684109302] 'range keys from in-memory index tree' (duration: 228.007369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:42.560629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:42.239127Z","time spent":"321.490131ms","remote":"127.0.0.1:47036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:04:43.508979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310563614568046,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:04:43.560772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:42.561605Z","time spent":"999.162279ms","remote":"127.0.0.1:46728","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-09T02:04:43.560996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.795836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:04:43.560771Z","caller":"traceutil/trace.go:171","msg":"trace[2132453907] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"552.641432ms","start":"2026-04-09T02:04:43.008105Z","end":"2026-04-09T02:04:43.560747Z","steps":["trace[2132453907] 'read index received' (duration: 552.600251ms)","trace[2132453907] 'applied index is now lower than readState.Index' (duration: 39.851µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:04:43.561042Z","caller":"traceutil/trace.go:171","msg":"trace[1654174373] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1094; }","duration":"552.96583ms","start":"2026-04-09T02:04:43.008066Z","end":"2026-04-09T02:04:43.561032Z","steps":["trace[1654174373] 'agreement among raft nodes before linearized reading' (duration: 552.751615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:43.561078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.008048Z","time spent":"553.018192ms","remote":"127.0.0.1:47218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":112,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:04:44.293661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310563614568050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-09T02:04:44.794444Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310563614568050,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-09T02:04:44.881834Z","caller":"traceutil/trace.go:171","msg":"trace[1755265657] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"1.342254879s","start":"2026-04-09T02:04:43.53955Z","end":"2026-04-09T02:04:44.881805Z","steps":["trace[1755265657] 'process raft request' (duration: 1.342091714s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.881993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.539527Z","time spent":"1.342384673s","remote":"127.0.0.1:46994","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-04-09T02:04:44.920787Z","caller":"traceutil/trace.go:171","msg":"trace[1104976211] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"1.358868806s","start":"2026-04-09T02:04:43.561883Z","end":"2026-04-09T02:04:44.920752Z","steps":["trace[1104976211] 'process raft request' (duration: 1.358585609s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:44.920849Z","caller":"traceutil/trace.go:171","msg":"trace[1137567167] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"1.080937082s","start":"2026-04-09T02:04:43.839889Z","end":"2026-04-09T02:04:44.920826Z","steps":["trace[1137567167] 'process raft request' (duration: 1.080777588s)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:04:44.920823Z","caller":"traceutil/trace.go:171","msg":"trace[1660970648] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1141; }","duration":"1.128067284s","start":"2026-04-09T02:04:43.792682Z","end":"2026-04-09T02:04:44.920749Z","steps":["trace[1660970648] 'read index received' (duration: 1.088917213s)","trace[1660970648] 'applied index is now lower than readState.Index' (duration: 39.149001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:04:44.920938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.56186Z","time spent":"1.358990579s","remote":"127.0.0.1:46728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:04:44.920954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.839867Z","time spent":"1.081031954s","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:04:44.921101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07324766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:44.921147Z","caller":"traceutil/trace.go:171","msg":"trace[1054199410] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1097; }","duration":"1.073318242s","start":"2026-04-09T02:04:43.847813Z","end":"2026-04-09T02:04:44.921131Z","steps":["trace[1054199410] 'agreement among raft nodes before linearized reading' (duration: 1.07325076s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.921178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.847801Z","time spent":"1.073368713s","remote":"127.0.0.1:40692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:04:44.921197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.003127ms","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-04-09T02:04:44.921226Z","caller":"traceutil/trace.go:171","msg":"trace[1283027036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"702.093839ms","start":"2026-04-09T02:04:44.219125Z","end":"2026-04-09T02:04:44.921219Z","steps":["trace[1283027036] 'agreement among raft nodes before linearized reading' (duration: 701.967146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.921252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:44.219101Z","time spent":"702.146541ms","remote":"127.0.0.1:46994","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-04-09T02:04:44.921326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058242764s","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-04-09T02:04:44.921363Z","caller":"traceutil/trace.go:171","msg":"trace[190460707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"1.058303186s","start":"2026-04-09T02:04:43.863051Z","end":"2026-04-09T02:04:44.921354Z","steps":["trace[190460707] 'agreement among raft nodes before linearized reading' (duration: 1.058176623s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.921367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.063773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T02:04:44.921384Z","caller":"traceutil/trace.go:171","msg":"trace[391805794] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1097; }","duration":"849.125664ms","start":"2026-04-09T02:04:44.072254Z","end":"2026-04-09T02:04:44.92138Z","steps":["trace[391805794] 'agreement among raft nodes before linearized reading' (duration: 849.097723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.921338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.433295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T02:04:44.921404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:44.072224Z","time spent":"849.176035ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-04-09T02:04:44.92141Z","caller":"traceutil/trace.go:171","msg":"trace[1300526686] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1097; }","duration":"528.541016ms","start":"2026-04-09T02:04:44.392861Z","end":"2026-04-09T02:04:44.921402Z","steps":["trace[1300526686] 'agreement among raft nodes before linearized reading' (duration: 528.453154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.921421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06796637s","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-04-09T02:04:44.921459Z","caller":"traceutil/trace.go:171","msg":"trace[1916473253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"1.068042902s","start":"2026-04-09T02:04:43.853407Z","end":"2026-04-09T02:04:44.92145Z","steps":["trace[1916473253] 'agreement among raft nodes before linearized reading' (duration: 1.067922179s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.921387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.863039Z","time spent":"1.058342857s","remote":"127.0.0.1:46994","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-04-09T02:04:44.921486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058295135s","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-04-09T02:04:44.921493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.853387Z","time spent":"1.068099854s","remote":"127.0.0.1:46994","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-04-09T02:04:44.921505Z","caller":"traceutil/trace.go:171","msg":"trace[1285181198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"1.058338696s","start":"2026-04-09T02:04:43.863161Z","end":"2026-04-09T02:04:44.9215Z","steps":["trace[1285181198] 'agreement among raft nodes before linearized reading' (duration: 1.058282134s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.921523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.863149Z","time spent":"1.058370246s","remote":"127.0.0.1:46994","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-04-09T02:04:44.921431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:44.392844Z","time spent":"528.580918ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-09T02:04:44.922012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034421486s","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-04-09T02:04:44.922208Z","caller":"traceutil/trace.go:171","msg":"trace[1472041749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1097; }","duration":"1.03457247s","start":"2026-04-09T02:04:43.887537Z","end":"2026-04-09T02:04:44.922109Z","steps":["trace[1472041749] 'agreement among raft nodes before linearized reading' (duration: 1.033551184s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.92244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.887521Z","time spent":"1.034817077s","remote":"127.0.0.1:46994","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-04-09T02:04:44.922722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130025386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:44.922773Z","caller":"traceutil/trace.go:171","msg":"trace[615718579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"1.130131908s","start":"2026-04-09T02:04:43.792629Z","end":"2026-04-09T02:04:44.922761Z","steps":["trace[615718579] 'agreement among raft nodes before linearized reading' (duration: 1.128362812s)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:44.922814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:04:43.79261Z","time spent":"1.130195919s","remote":"127.0.0.1:46702","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-04-09T02:04:46.965331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.837574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:46.96542Z","caller":"traceutil/trace.go:171","msg":"trace[1063219134] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1103; }","duration":"120.996618ms","start":"2026-04-09T02:04:46.844404Z","end":"2026-04-09T02:04:46.965401Z","steps":["trace[1063219134] 'count revisions from in-memory index tree' (duration: 120.731982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:04:46.965545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.91255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:04:46.965621Z","caller":"traceutil/trace.go:171","msg":"trace[1148948773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"172.032743ms","start":"2026-04-09T02:04:46.793571Z","end":"2026-04-09T02:04:46.965604Z","steps":["trace[1148948773] 'range keys from in-memory index tree' (duration: 171.858859ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:13.570678Z","caller":"traceutil/trace.go:171","msg":"trace[1493402206] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"433.002758ms","start":"2026-04-09T02:05:13.137653Z","end":"2026-04-09T02:05:13.570656Z","steps":["trace[1493402206] 'read index received' (duration: 432.827983ms)","trace[1493402206] 'applied index is now lower than readState.Index' (duration: 173.925µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:13.571188Z","caller":"traceutil/trace.go:171","msg":"trace[1743579830] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"515.583034ms","start":"2026-04-09T02:05:13.055562Z","end":"2026-04-09T02:05:13.571145Z","steps":["trace[1743579830] 'process raft request' (duration: 514.981538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:13.571306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:13.055519Z","time spent":"515.711217ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T02:05:13.571668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.793394ms","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-04-09T02:05:13.571697Z","caller":"traceutil/trace.go:171","msg":"trace[746101123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"432.835465ms","start":"2026-04-09T02:05:13.138854Z","end":"2026-04-09T02:05:13.57169Z","steps":["trace[746101123] 'agreement among raft nodes before linearized reading' (duration: 432.721801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:13.571716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:13.13885Z","time spent":"432.862005ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:05:13.571705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.041876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T02:05:13.571757Z","caller":"traceutil/trace.go:171","msg":"trace[4716655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"434.116458ms","start":"2026-04-09T02:05:13.137625Z","end":"2026-04-09T02:05:13.571741Z","steps":["trace[4716655] 'agreement among raft nodes before linearized reading' (duration: 433.937763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:13.571793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:13.137611Z","time spent":"434.173119ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T02:05:13.571833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.756596ms","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-04-09T02:05:13.571851Z","caller":"traceutil/trace.go:171","msg":"trace[1956599898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1240; }","duration":"425.818898ms","start":"2026-04-09T02:05:13.146027Z","end":"2026-04-09T02:05:13.571846Z","steps":["trace[1956599898] 'agreement among raft nodes before linearized reading' (duration: 425.770106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:13.571866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:13.145967Z","time spent":"425.89499ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T02:05:13.571935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.6475ms","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-04-09T02:05:13.571948Z","caller":"traceutil/trace.go:171","msg":"trace[1522121581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"426.6698ms","start":"2026-04-09T02:05:13.145274Z","end":"2026-04-09T02:05:13.571944Z","steps":["trace[1522121581] 'agreement among raft nodes before linearized reading' (duration: 426.628389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:13.571964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:13.145269Z","time spent":"426.68962ms","remote":"127.0.0.1:46994","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-04-09T02:05:13.572081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.265529ms","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-04-09T02:05:13.572096Z","caller":"traceutil/trace.go:171","msg":"trace[1774806823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"429.28971ms","start":"2026-04-09T02:05:13.142802Z","end":"2026-04-09T02:05:13.572091Z","steps":["trace[1774806823] 'agreement among raft nodes before linearized reading' (duration: 429.243499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:13.572111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:13.14278Z","time spent":"429.327551ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T02:05:13.746279Z","caller":"traceutil/trace.go:171","msg":"trace[1614392550] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"169.389853ms","start":"2026-04-09T02:05:13.576868Z","end":"2026-04-09T02:05:13.746258Z","steps":["trace[1614392550] 'process raft request' (duration: 169.339181ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:13.746343Z","caller":"traceutil/trace.go:171","msg":"trace[1387475696] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"170.143292ms","start":"2026-04-09T02:05:13.576144Z","end":"2026-04-09T02:05:13.746287Z","steps":["trace[1387475696] 'process raft request' (duration: 170.03712ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:13.746458Z","caller":"traceutil/trace.go:171","msg":"trace[918413106] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"169.374672ms","start":"2026-04-09T02:05:13.577049Z","end":"2026-04-09T02:05:13.746423Z","steps":["trace[918413106] 'process raft request' (duration: 169.180177ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:13.746695Z","caller":"traceutil/trace.go:171","msg":"trace[1020065] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"170.590155ms","start":"2026-04-09T02:05:13.576068Z","end":"2026-04-09T02:05:13.746659Z","steps":["trace[1020065] 'process raft request' (duration: 169.956188ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:15.809086Z","caller":"traceutil/trace.go:171","msg":"trace[1754266083] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"232.748559ms","start":"2026-04-09T02:05:15.576316Z","end":"2026-04-09T02:05:15.809065Z","steps":["trace[1754266083] 'process raft request' (duration: 232.582964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.237619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.375835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:05:16.237731Z","caller":"traceutil/trace.go:171","msg":"trace[1565464086] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1308; }","duration":"623.390798ms","start":"2026-04-09T02:05:15.614323Z","end":"2026-04-09T02:05:16.237714Z","steps":["trace[1565464086] 'read index received' (duration: 194.60862ms)","trace[1565464086] 'applied index is now lower than readState.Index' (duration: 428.780138ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:16.237886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.556882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-09T02:05:16.23785Z","caller":"traceutil/trace.go:171","msg":"trace[2011139415] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"624.739124ms","start":"2026-04-09T02:05:15.613058Z","end":"2026-04-09T02:05:16.237797Z","steps":["trace[2011139415] 'process raft request' (duration: 227.092001ms)","trace[2011139415] 'compare' (duration: 396.708107ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:16.237924Z","caller":"traceutil/trace.go:171","msg":"trace[860292590] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"623.621544ms","start":"2026-04-09T02:05:15.614293Z","end":"2026-04-09T02:05:16.237914Z","steps":["trace[860292590] 'agreement among raft nodes before linearized reading' (duration: 623.499481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.237954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:15.61428Z","time spent":"623.665444ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T02:05:16.238045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:15.613037Z","time spent":"624.903557ms","remote":"127.0.0.1:46994","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-04-09T02:05:16.238175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.815548ms","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-04-09T02:05:16.238212Z","caller":"traceutil/trace.go:171","msg":"trace[332375037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"487.88321ms","start":"2026-04-09T02:05:15.75032Z","end":"2026-04-09T02:05:16.238204Z","steps":["trace[332375037] 'agreement among raft nodes before linearized reading' (duration: 487.790488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.23846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.373314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:16.238493Z","caller":"traceutil/trace.go:171","msg":"trace[110594004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"443.446926ms","start":"2026-04-09T02:05:15.795036Z","end":"2026-04-09T02:05:16.238482Z","steps":["trace[110594004] 'agreement among raft nodes before linearized reading' (duration: 443.397025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.238508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:15.795015Z","time spent":"443.489476ms","remote":"127.0.0.1:46702","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-04-09T02:05:16.238554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.433338ms","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-04-09T02:05:16.238595Z","caller":"traceutil/trace.go:171","msg":"trace[304831771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1255; }","duration":"487.48732ms","start":"2026-04-09T02:05:15.751098Z","end":"2026-04-09T02:05:16.238585Z","steps":["trace[304831771] 'agreement among raft nodes before linearized reading' (duration: 487.362657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.238624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:15.751093Z","time spent":"487.523581ms","remote":"127.0.0.1:46994","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-04-09T02:05:16.238672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.178588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-09T02:05:16.238726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.560172ms","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-04-09T02:05:16.238742Z","caller":"traceutil/trace.go:171","msg":"trace[1542655019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1255; }","duration":"487.589972ms","start":"2026-04-09T02:05:15.751147Z","end":"2026-04-09T02:05:16.238737Z","steps":["trace[1542655019] 'agreement among raft nodes before linearized reading' (duration: 487.539431ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.23874Z","caller":"traceutil/trace.go:171","msg":"trace[2048352452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1255; }","duration":"488.266341ms","start":"2026-04-09T02:05:15.750458Z","end":"2026-04-09T02:05:16.238724Z","steps":["trace[2048352452] 'agreement among raft nodes before linearized reading' (duration: 488.067826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:16.238795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:15.750438Z","time spent":"488.345402ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-09T02:05:16.238761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:15.751141Z","time spent":"487.617573ms","remote":"127.0.0.1:46994","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-04-09T02:05:16.238239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:15.750303Z","time spent":"487.929372ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-09T02:05:16.424717Z","caller":"traceutil/trace.go:171","msg":"trace[1199284776] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"182.12672ms","start":"2026-04-09T02:05:16.242537Z","end":"2026-04-09T02:05:16.424663Z","steps":["trace[1199284776] 'process raft request' (duration: 181.968286ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.432357Z","caller":"traceutil/trace.go:171","msg":"trace[496944578] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"189.341471ms","start":"2026-04-09T02:05:16.243001Z","end":"2026-04-09T02:05:16.432343Z","steps":["trace[496944578] 'process raft request' (duration: 189.118974ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.432421Z","caller":"traceutil/trace.go:171","msg":"trace[699412763] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"189.181765ms","start":"2026-04-09T02:05:16.243229Z","end":"2026-04-09T02:05:16.43241Z","steps":["trace[699412763] 'process raft request' (duration: 189.070573ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.432398Z","caller":"traceutil/trace.go:171","msg":"trace[980283310] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"187.451771ms","start":"2026-04-09T02:05:16.244922Z","end":"2026-04-09T02:05:16.432374Z","steps":["trace[980283310] 'process raft request' (duration: 187.41257ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:16.432436Z","caller":"traceutil/trace.go:171","msg":"trace[985767737] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"189.324091ms","start":"2026-04-09T02:05:16.24309Z","end":"2026-04-09T02:05:16.432415Z","steps":["trace[985767737] 'process raft request' (duration: 189.167237ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.312476Z","caller":"traceutil/trace.go:171","msg":"trace[1209140447] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"185.353555ms","start":"2026-04-09T02:05:17.127099Z","end":"2026-04-09T02:05:17.312453Z","steps":["trace[1209140447] 'read index received' (duration: 185.211162ms)","trace[1209140447] 'applied index is now lower than readState.Index' (duration: 141.333µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:17.312684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.57113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:05:17.312746Z","caller":"traceutil/trace.go:171","msg":"trace[785509906] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1262; }","duration":"185.676853ms","start":"2026-04-09T02:05:17.127058Z","end":"2026-04-09T02:05:17.312734Z","steps":["trace[785509906] 'agreement among raft nodes before linearized reading' (duration: 185.55637ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:17.313225Z","caller":"traceutil/trace.go:171","msg":"trace[1227638050] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"246.856212ms","start":"2026-04-09T02:05:17.066356Z","end":"2026-04-09T02:05:17.313212Z","steps":["trace[1227638050] 'process raft request' (duration: 245.961029ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.56838Z","caller":"traceutil/trace.go:171","msg":"trace[1724871736] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"132.59171ms","start":"2026-04-09T02:05:18.435743Z","end":"2026-04-09T02:05:18.568335Z","steps":["trace[1724871736] 'read index received' (duration: 132.448826ms)","trace[1724871736] 'applied index is now lower than readState.Index' (duration: 141.914µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:18.568407Z","caller":"traceutil/trace.go:171","msg":"trace[1898080140] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"137.389606ms","start":"2026-04-09T02:05:18.430989Z","end":"2026-04-09T02:05:18.568379Z","steps":["trace[1898080140] 'process raft request' (duration: 137.224762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.568616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.851926ms","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-04-09T02:05:18.568656Z","caller":"traceutil/trace.go:171","msg":"trace[575407621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1269; }","duration":"132.930197ms","start":"2026-04-09T02:05:18.435715Z","end":"2026-04-09T02:05:18.568645Z","steps":["trace[575407621] 'agreement among raft nodes before linearized reading' (duration: 132.771833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.568684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.553567ms","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-04-09T02:05:18.568709Z","caller":"traceutil/trace.go:171","msg":"trace[280852734] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1269; }","duration":"132.65313ms","start":"2026-04-09T02:05:18.436049Z","end":"2026-04-09T02:05:18.568703Z","steps":["trace[280852734] 'agreement among raft nodes before linearized reading' (duration: 132.532087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.568789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.418384ms","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-04-09T02:05:18.568804Z","caller":"traceutil/trace.go:171","msg":"trace[334965214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1269; }","duration":"132.559817ms","start":"2026-04-09T02:05:18.43624Z","end":"2026-04-09T02:05:18.5688Z","steps":["trace[334965214] 'agreement among raft nodes before linearized reading' (duration: 132.513716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:18.568809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.756476ms","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-04-09T02:05:18.568835Z","caller":"traceutil/trace.go:171","msg":"trace[398359061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"131.798817ms","start":"2026-04-09T02:05:18.437028Z","end":"2026-04-09T02:05:18.568827Z","steps":["trace[398359061] 'agreement among raft nodes before linearized reading' (duration: 131.724855ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.679709Z","caller":"traceutil/trace.go:171","msg":"trace[619041755] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"108.200933ms","start":"2026-04-09T02:05:18.571489Z","end":"2026-04-09T02:05:18.67969Z","steps":["trace[619041755] 'process raft request' (duration: 108.06777ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.713496Z","caller":"traceutil/trace.go:171","msg":"trace[1410561309] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"141.256208ms","start":"2026-04-09T02:05:18.572215Z","end":"2026-04-09T02:05:18.713471Z","steps":["trace[1410561309] 'process raft request' (duration: 141.071933ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.713768Z","caller":"traceutil/trace.go:171","msg":"trace[901332207] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"140.558819ms","start":"2026-04-09T02:05:18.573192Z","end":"2026-04-09T02:05:18.713751Z","steps":["trace[901332207] 'process raft request' (duration: 140.237321ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:18.713869Z","caller":"traceutil/trace.go:171","msg":"trace[1574351527] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"137.691254ms","start":"2026-04-09T02:05:18.576169Z","end":"2026-04-09T02:05:18.713861Z","steps":["trace[1574351527] 'process raft request' (duration: 137.461267ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:27.735901Z","caller":"traceutil/trace.go:171","msg":"trace[391318042] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"101.164856ms","start":"2026-04-09T02:05:27.634708Z","end":"2026-04-09T02:05:27.735873Z","steps":["trace[391318042] 'process raft request' (duration: 100.92767ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:30.148581Z","caller":"traceutil/trace.go:171","msg":"trace[2069862144] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"177.011274ms","start":"2026-04-09T02:05:29.971537Z","end":"2026-04-09T02:05:30.148548Z","steps":["trace[2069862144] 'read index received' (duration: 176.606393ms)","trace[2069862144] 'applied index is now lower than readState.Index' (duration: 403.651µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:30.148805Z","caller":"traceutil/trace.go:171","msg":"trace[1519398784] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"227.770137ms","start":"2026-04-09T02:05:29.920999Z","end":"2026-04-09T02:05:30.148769Z","steps":["trace[1519398784] 'process raft request' (duration: 227.318185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.148852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.309622ms","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-04-09T02:05:30.148911Z","caller":"traceutil/trace.go:171","msg":"trace[1902299707] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1359; }","duration":"177.473256ms","start":"2026-04-09T02:05:29.97142Z","end":"2026-04-09T02:05:30.148893Z","steps":["trace[1902299707] 'agreement among raft nodes before linearized reading' (duration: 177.25488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.745663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.043717ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T02:05:30.745846Z","caller":"traceutil/trace.go:171","msg":"trace[1764419390] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1417; }","duration":"544.860868ms","start":"2026-04-09T02:05:30.200968Z","end":"2026-04-09T02:05:30.745829Z","steps":["trace[1764419390] 'read index received' (duration: 3.254266ms)","trace[1764419390] 'applied index is now lower than readState.Index' (duration: 541.604752ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:30.746Z","caller":"traceutil/trace.go:171","msg":"trace[462801506] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"592.499909ms","start":"2026-04-09T02:05:30.153456Z","end":"2026-04-09T02:05:30.745956Z","steps":["trace[462801506] 'process raft request' (duration: 50.851945ms)","trace[462801506] 'compare' (duration: 538.446438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:05:30.746159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:30.153427Z","time spent":"592.644673ms","remote":"127.0.0.1:46994","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-04-09T02:05:30.746248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.495323ms","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-04-09T02:05:30.746325Z","caller":"traceutil/trace.go:171","msg":"trace[622293394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1360; }","duration":"124.611867ms","start":"2026-04-09T02:05:30.621696Z","end":"2026-04-09T02:05:30.746308Z","steps":["trace[622293394] 'agreement among raft nodes before linearized reading' (duration: 124.349061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.746684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.568537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-09T02:05:30.746796Z","caller":"traceutil/trace.go:171","msg":"trace[143547206] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1360; }","duration":"545.838745ms","start":"2026-04-09T02:05:30.200927Z","end":"2026-04-09T02:05:30.746766Z","steps":["trace[143547206] 'agreement among raft nodes before linearized reading' (duration: 545.040494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:30.746872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T02:05:30.200914Z","time spent":"545.945117ms","remote":"127.0.0.1:46994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-09T02:05:34.00211Z","caller":"traceutil/trace.go:171","msg":"trace[576147323] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"143.560499ms","start":"2026-04-09T02:05:33.858523Z","end":"2026-04-09T02:05:34.002084Z","steps":["trace[576147323] 'process raft request' (duration: 143.409235ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:50.966026Z","caller":"traceutil/trace.go:171","msg":"trace[1669514044] linearizableReadLoop","detail":"{readStateIndex:1516; appliedIndex:1515; }","duration":"113.320698ms","start":"2026-04-09T02:05:50.852681Z","end":"2026-04-09T02:05:50.966001Z","steps":["trace[1669514044] 'read index received' (duration: 106.838797ms)","trace[1669514044] 'applied index is now lower than readState.Index' (duration: 6.480781ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:05:50.966211Z","caller":"traceutil/trace.go:171","msg":"trace[396717271] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"135.490366ms","start":"2026-04-09T02:05:50.830605Z","end":"2026-04-09T02:05:50.966096Z","steps":["trace[396717271] 'process raft request' (duration: 128.987354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:05:50.966241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.551135ms","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-04-09T02:05:50.966241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.371208ms","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-04-09T02:05:50.966665Z","caller":"traceutil/trace.go:171","msg":"trace[2138901679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1454; }","duration":"113.924215ms","start":"2026-04-09T02:05:50.852669Z","end":"2026-04-09T02:05:50.966593Z","steps":["trace[2138901679] 'agreement among raft nodes before linearized reading' (duration: 113.471213ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:05:50.966738Z","caller":"traceutil/trace.go:171","msg":"trace[1624694183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1454; }","duration":"108.882542ms","start":"2026-04-09T02:05:50.857829Z","end":"2026-04-09T02:05:50.966711Z","steps":["trace[1624694183] 'agreement among raft nodes before linearized reading' (duration: 108.330477ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.31971Z","caller":"traceutil/trace.go:171","msg":"trace[742363028] linearizableReadLoop","detail":"{readStateIndex:1640; appliedIndex:1639; }","duration":"133.009962ms","start":"2026-04-09T02:06:17.186671Z","end":"2026-04-09T02:06:17.319681Z","steps":["trace[742363028] 'read index received' (duration: 132.712784ms)","trace[742363028] 'applied index is now lower than readState.Index' (duration: 295.548µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:06:17.319855Z","caller":"traceutil/trace.go:171","msg":"trace[1977022129] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"172.626249ms","start":"2026-04-09T02:06:17.147182Z","end":"2026-04-09T02:06:17.319809Z","steps":["trace[1977022129] 'process raft request' (duration: 172.24455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:17.319942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.257888ms","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:531"} {"level":"info","ts":"2026-04-09T02:06:17.320306Z","caller":"traceutil/trace.go:171","msg":"trace[542889696] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1573; }","duration":"133.669369ms","start":"2026-04-09T02:06:17.186619Z","end":"2026-04-09T02:06:17.320288Z","steps":["trace[542889696] 'agreement among raft nodes before linearized reading' (duration: 133.185846ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.503697Z","caller":"traceutil/trace.go:171","msg":"trace[2127696491] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"179.080318ms","start":"2026-04-09T02:06:17.324579Z","end":"2026-04-09T02:06:17.50366Z","steps":["trace[2127696491] 'process raft request' (duration: 178.841521ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.561917Z","caller":"traceutil/trace.go:171","msg":"trace[1806430089] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"237.250521ms","start":"2026-04-09T02:06:17.324639Z","end":"2026-04-09T02:06:17.561889Z","steps":["trace[1806430089] 'process raft request' (duration: 237.052826ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.562383Z","caller":"traceutil/trace.go:171","msg":"trace[1145963876] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"237.690822ms","start":"2026-04-09T02:06:17.324662Z","end":"2026-04-09T02:06:17.562353Z","steps":["trace[1145963876] 'process raft request' (duration: 237.174729ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.562484Z","caller":"traceutil/trace.go:171","msg":"trace[1715624563] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"237.683243ms","start":"2026-04-09T02:06:17.324767Z","end":"2026-04-09T02:06:17.56245Z","steps":["trace[1715624563] 'process raft request' (duration: 237.388855ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:17.562558Z","caller":"traceutil/trace.go:171","msg":"trace[1700992518] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"237.762884ms","start":"2026-04-09T02:06:17.324785Z","end":"2026-04-09T02:06:17.562548Z","steps":["trace[1700992518] 'process raft request' (duration: 237.501167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:17.66969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.331361ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-09T02:06:17.669787Z","caller":"traceutil/trace.go:171","msg":"trace[1247965622] linearizableReadLoop","detail":"{readStateIndex:1645; appliedIndex:1640; }","duration":"289.187321ms","start":"2026-04-09T02:06:17.380582Z","end":"2026-04-09T02:06:17.669769Z","steps":["trace[1247965622] 'read index received' (duration: 122.815955ms)","trace[1247965622] 'applied index is now lower than readState.Index' (duration: 166.370216ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T02:06:17.670038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.435217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T02:06:17.670072Z","caller":"traceutil/trace.go:171","msg":"trace[304493792] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1578; }","duration":"289.513599ms","start":"2026-04-09T02:06:17.380547Z","end":"2026-04-09T02:06:17.67006Z","steps":["trace[304493792] 'agreement among raft nodes before linearized reading' (duration: 289.266992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.794141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.267244ms","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-04-09T02:06:25.794185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.703546ms","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-04-09T02:06:25.794338Z","caller":"traceutil/trace.go:171","msg":"trace[1172829868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1611; }","duration":"124.495289ms","start":"2026-04-09T02:06:25.669822Z","end":"2026-04-09T02:06:25.794317Z","steps":["trace[1172829868] 'range keys from in-memory index tree' (duration: 124.137109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:25.794567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.767368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-09T02:06:25.794654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.628178ms","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-04-09T02:06:25.794668Z","caller":"traceutil/trace.go:171","msg":"trace[160018223] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1611; }","duration":"118.911033ms","start":"2026-04-09T02:06:25.675734Z","end":"2026-04-09T02:06:25.794645Z","steps":["trace[160018223] 'range keys from in-memory index tree' (duration: 118.567864ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:25.794693Z","caller":"traceutil/trace.go:171","msg":"trace[694714828] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1611; }","duration":"120.682009ms","start":"2026-04-09T02:06:25.674001Z","end":"2026-04-09T02:06:25.794682Z","steps":["trace[694714828] 'range keys from in-memory index tree' (duration: 120.542206ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:06:25.794373Z","caller":"traceutil/trace.go:171","msg":"trace[2080107178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1611; }","duration":"121.996114ms","start":"2026-04-09T02:06:25.672351Z","end":"2026-04-09T02:06:25.794348Z","steps":["trace[2080107178] 'range keys from in-memory index tree' (duration: 121.48284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:06:52.03627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.113774ms","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-04-09T02:06:52.036353Z","caller":"traceutil/trace.go:171","msg":"trace[750827636] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1732; }","duration":"107.251707ms","start":"2026-04-09T02:06:51.929083Z","end":"2026-04-09T02:06:52.036335Z","steps":["trace[750827636] 'range keys from in-memory index tree' (duration: 106.856298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T02:08:22.745592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.196623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-09T02:08:22.745723Z","caller":"traceutil/trace.go:171","msg":"trace[1997540162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2149; }","duration":"115.386107ms","start":"2026-04-09T02:08:22.630318Z","end":"2026-04-09T02:08:22.745704Z","steps":["trace[1997540162] 'range keys from in-memory index tree' (duration: 114.962936ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T02:08:22.941085Z","caller":"traceutil/trace.go:171","msg":"trace[1165244588] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"134.491185ms","start":"2026-04-09T02:08:22.806571Z","end":"2026-04-09T02:08:22.941062Z","steps":["trace[1165244588] 'process raft request' (duration: 76.684258ms)","trace[1165244588] 'compare' (duration: 57.642223ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T02:08:44.645288Z","caller":"traceutil/trace.go:171","msg":"trace[1682152030] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"205.85606ms","start":"2026-04-09T02:08:44.439358Z","end":"2026-04-09T02:08:44.645214Z","steps":["trace[1682152030] 'process raft request' (duration: 205.685925ms)"],"step_count":1}