{"level":"warn","ts":"2026-04-07T05:53:15.320797Z","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-07T05:53:15.320888Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-07T05:53:15.320952Z","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-07T05:53:15.320959Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-07T05:53:15.320976Z","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-07T05:53:15.321635Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-07T05:53:15.321808Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-07T05:53:15.32568Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.649277ms"} {"level":"info","ts":"2026-04-07T05:53:15.334265Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-07T05:53:15.334345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T05:53:15.334371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-07T05:53:15.33439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T05:53:15.334406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-07T05:53:15.334449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-07T05:53:15.338898Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T05:53:15.341454Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T05:53:15.344141Z","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-07T05:53:15.346363Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T05:53:15.346575Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T05:53:15.346868Z","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-07T05:53:15.346958Z","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-07T05:53:15.346971Z","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-07T05:53:15.348868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-07T05:53:15.349065Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-07T05:53:15.349687Z","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-07T05:53:15.349853Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-07T05:53:15.349924Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-07T05:53:15.350039Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T05:53:15.350118Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T05:53:16.135633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T05:53:16.135704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T05:53:16.135783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-07T05:53:16.135819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-07T05:53:16.135843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-07T05:53:16.135926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-07T05:53:16.135956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-07T05:53:16.137303Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T05:53:16.137319Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:53:16.137527Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:53:16.137567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T05:53:16.137625Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T05:53:16.137662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T05:53:16.140133Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:53:16.140218Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:53:16.140245Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T05:53:16.141186Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-07T05:53:16.141379Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-07T05:53:18.068908Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792771705,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T05:53:18.293313Z","caller":"traceutil/trace.go:171","msg":"trace[2027656651] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"736.251203ms","start":"2026-04-07T05:53:17.557033Z","end":"2026-04-07T05:53:18.293285Z","steps":["trace[2027656651] 'process raft request' (duration: 736.038016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.29413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.557015Z","time spent":"736.384358ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:53:18.897965Z","caller":"traceutil/trace.go:171","msg":"trace[1395039374] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.340179684s","start":"2026-04-07T05:53:17.557709Z","end":"2026-04-07T05:53:18.897889Z","steps":["trace[1395039374] 'process raft request' (duration: 1.339880525s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:18.89802Z","caller":"traceutil/trace.go:171","msg":"trace[1735926897] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.288304217s","start":"2026-04-07T05:53:17.609678Z","end":"2026-04-07T05:53:18.897982Z","steps":["trace[1735926897] 'process raft request' (duration: 1.288108981s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:18.898078Z","caller":"traceutil/trace.go:171","msg":"trace[943453781] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.288157002s","start":"2026-04-07T05:53:17.609916Z","end":"2026-04-07T05:53:18.898073Z","steps":["trace[943453781] 'process raft request' (duration: 1.287903844s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:18.898075Z","caller":"traceutil/trace.go:171","msg":"trace[1621271021] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"1.329903634s","start":"2026-04-07T05:53:17.568141Z","end":"2026-04-07T05:53:18.898045Z","steps":["trace[1621271021] 'read index received' (duration: 725.181137ms)","trace[1621271021] 'applied index is now lower than readState.Index' (duration: 604.721657ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:53:18.898165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.609906Z","time spent":"1.288210765s","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:53:18.898174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.609672Z","time spent":"1.288442712s","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:53:18.898281Z","caller":"traceutil/trace.go:171","msg":"trace[111020129] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.287733678s","start":"2026-04-07T05:53:17.610535Z","end":"2026-04-07T05:53:18.898269Z","steps":["trace[111020129] 'process raft request' (duration: 1.287356786s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:18.898329Z","caller":"traceutil/trace.go:171","msg":"trace[2075461565] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.26474148s","start":"2026-04-07T05:53:17.633575Z","end":"2026-04-07T05:53:18.898317Z","steps":["trace[2075461565] 'process raft request' (duration: 1.26442249s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.898398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.633559Z","time spent":"1.264802871s","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:53:18.898401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.610515Z","time spent":"1.287803451s","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:53:18.898402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.557698Z","time spent":"1.340426063s","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:53:18.898651Z","caller":"traceutil/trace.go:171","msg":"trace[1160877656] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.28902685s","start":"2026-04-07T05:53:17.609613Z","end":"2026-04-07T05:53:18.89864Z","steps":["trace[1160877656] 'process raft request' (duration: 1.288144312s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.898706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.609596Z","time spent":"1.289083273s","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:53:18.898788Z","caller":"traceutil/trace.go:171","msg":"trace[42620953] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.281720906s","start":"2026-04-07T05:53:17.617056Z","end":"2026-04-07T05:53:18.898777Z","steps":["trace[42620953] 'process raft request' (duration: 1.280897789s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.898834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.617046Z","time spent":"1.281766477s","remote":"127.0.0.1:48116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:53:18.898042Z","caller":"traceutil/trace.go:171","msg":"trace[1183141990] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.288020098s","start":"2026-04-07T05:53:17.610006Z","end":"2026-04-07T05:53:18.898026Z","steps":["trace[1183141990] 'process raft request' (duration: 1.287833352s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.899015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.609997Z","time spent":"1.28899668s","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:53:18.89906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330866525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.899126Z","caller":"traceutil/trace.go:171","msg":"trace[1617523535] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"1.331017521s","start":"2026-04-07T05:53:17.568097Z","end":"2026-04-07T05:53:18.899114Z","steps":["trace[1617523535] 'agreement among raft nodes before linearized reading' (duration: 1.330834715s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.899158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.568083Z","time spent":"1.331068292s","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-07T05:53:18.899285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291738358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T05:53:18.899297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239735786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.899331Z","caller":"traceutil/trace.go:171","msg":"trace[350647527] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:0; response_revision:11; }","duration":"1.29180292s","start":"2026-04-07T05:53:17.607508Z","end":"2026-04-07T05:53:18.899311Z","steps":["trace[350647527] 'agreement among raft nodes before linearized reading' (duration: 1.291735018s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:18.899333Z","caller":"traceutil/trace.go:171","msg":"trace[328507748] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.239799498s","start":"2026-04-07T05:53:17.659523Z","end":"2026-04-07T05:53:18.899322Z","steps":["trace[328507748] 'agreement among raft nodes before linearized reading' (duration: 1.239744097s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.89936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.659507Z","time spent":"1.239846949s","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T05:53:18.899392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282534863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T05:53:18.899449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.337568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.899481Z","caller":"traceutil/trace.go:171","msg":"trace[1217213264] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"391.418321ms","start":"2026-04-07T05:53:18.508054Z","end":"2026-04-07T05:53:18.899472Z","steps":["trace[1217213264] 'agreement among raft nodes before linearized reading' (duration: 391.37036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.899496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184805161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.899417Z","caller":"traceutil/trace.go:171","msg":"trace[1677250105] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.282575213s","start":"2026-04-07T05:53:17.616835Z","end":"2026-04-07T05:53:18.89941Z","steps":["trace[1677250105] 'agreement among raft nodes before linearized reading' (duration: 1.282541722s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:18.899525Z","caller":"traceutil/trace.go:171","msg":"trace[876467887] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.184832182s","start":"2026-04-07T05:53:17.714683Z","end":"2026-04-07T05:53:18.899516Z","steps":["trace[876467887] 'agreement among raft nodes before linearized reading' (duration: 1.18479192s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.899549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.616825Z","time spent":"1.282713897s","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T05:53:18.899361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.607501Z","time spent":"1.291853521s","remote":"127.0.0.1:47896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-04-07T05:53:18.89961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.031876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.899658Z","caller":"traceutil/trace.go:171","msg":"trace[49939701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"329.133209ms","start":"2026-04-07T05:53:18.570512Z","end":"2026-04-07T05:53:18.899645Z","steps":["trace[49939701] 'agreement among raft nodes before linearized reading' (duration: 329.032186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.899551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.714659Z","time spent":"1.184886954s","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T05:53:18.899515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:18.50803Z","time spent":"391.475993ms","remote":"127.0.0.1:48202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-07T05:53:18.899691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:18.57048Z","time spent":"329.203441ms","remote":"127.0.0.1:47854","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-07T05:53:18.899728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283289396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.899752Z","caller":"traceutil/trace.go:171","msg":"trace[1215545192] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.283311517s","start":"2026-04-07T05:53:17.616433Z","end":"2026-04-07T05:53:18.899744Z","steps":["trace[1215545192] 'agreement among raft nodes before linearized reading' (duration: 1.283278876s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.899777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.616417Z","time spent":"1.283353739s","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T05:53:18.899694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290173247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-07T05:53:18.899867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290050104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.899904Z","caller":"traceutil/trace.go:171","msg":"trace[267293197] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.290134086s","start":"2026-04-07T05:53:17.609757Z","end":"2026-04-07T05:53:18.899891Z","steps":["trace[267293197] 'agreement among raft nodes before linearized reading' (duration: 1.290082614s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:18.899892Z","caller":"traceutil/trace.go:171","msg":"trace[87549856] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.290406755s","start":"2026-04-07T05:53:17.609461Z","end":"2026-04-07T05:53:18.899867Z","steps":["trace[87549856] 'agreement among raft nodes before linearized reading' (duration: 1.290212109s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.89994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.609741Z","time spent":"1.290185058s","remote":"127.0.0.1:47938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-07T05:53:18.899966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.609428Z","time spent":"1.290518298s","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-07T05:53:18.900221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.955608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-07T05:53:18.900304Z","caller":"traceutil/trace.go:171","msg":"trace[2122089420] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"392.099023ms","start":"2026-04-07T05:53:18.508181Z","end":"2026-04-07T05:53:18.90028Z","steps":["trace[2122089420] 'agreement among raft nodes before linearized reading' (duration: 391.957159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.90035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:18.508125Z","time spent":"392.217087ms","remote":"127.0.0.1:48224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-04-07T05:53:18.897892Z","caller":"traceutil/trace.go:171","msg":"trace[161704412] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.288321188s","start":"2026-04-07T05:53:17.609519Z","end":"2026-04-07T05:53:18.89784Z","steps":["trace[161704412] 'process raft request' (duration: 1.288197854s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:18.903799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:17.609487Z","time spent":"1.292618956s","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T05:53:44.506858Z","caller":"traceutil/trace.go:171","msg":"trace[1900961417] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"117.685144ms","start":"2026-04-07T05:53:44.389148Z","end":"2026-04-07T05:53:44.506833Z","steps":["trace[1900961417] 'read index received' (duration: 117.55381ms)","trace[1900961417] 'applied index is now lower than readState.Index' (duration: 130.264µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:53:44.506996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.823618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:53:44.507029Z","caller":"traceutil/trace.go:171","msg":"trace[1589093967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:465; }","duration":"117.912512ms","start":"2026-04-07T05:53:44.389106Z","end":"2026-04-07T05:53:44.507019Z","steps":["trace[1589093967] 'agreement among raft nodes before linearized reading' (duration: 117.825849ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:44.507267Z","caller":"traceutil/trace.go:171","msg":"trace[135552041] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"217.305814ms","start":"2026-04-07T05:53:44.289794Z","end":"2026-04-07T05:53:44.5071Z","steps":["trace[135552041] 'process raft request' (duration: 216.871201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:47.361852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.323949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:53:47.361949Z","caller":"traceutil/trace.go:171","msg":"trace[720111621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"351.462232ms","start":"2026-04-07T05:53:47.010465Z","end":"2026-04-07T05:53:47.361927Z","steps":["trace[720111621] 'range keys from in-memory index tree' (duration: 351.255566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:47.361991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:47.01045Z","time spent":"351.531905ms","remote":"127.0.0.1:47856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T05:53:51.473141Z","caller":"traceutil/trace.go:171","msg":"trace[494583508] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"232.082824ms","start":"2026-04-07T05:53:51.241022Z","end":"2026-04-07T05:53:51.473105Z","steps":["trace[494583508] 'read index received' (duration: 231.054561ms)","trace[494583508] 'applied index is now lower than readState.Index' (duration: 1.027083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:53:51.473311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.25898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8wjdg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T05:53:51.473275Z","caller":"traceutil/trace.go:171","msg":"trace[748021907] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"346.831784ms","start":"2026-04-07T05:53:51.126391Z","end":"2026-04-07T05:53:51.473222Z","steps":["trace[748021907] 'process raft request' (duration: 345.985407ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:51.473343Z","caller":"traceutil/trace.go:171","msg":"trace[886769320] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8wjdg; range_end:; response_count:1; response_revision:574; }","duration":"232.358303ms","start":"2026-04-07T05:53:51.240975Z","end":"2026-04-07T05:53:51.473333Z","steps":["trace[886769320] 'agreement among raft nodes before linearized reading' (duration: 232.25328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:51.473478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:51.12634Z","time spent":"347.035821ms","remote":"127.0.0.1:47944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:53:52.403546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.904488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:53:52.403683Z","caller":"traceutil/trace.go:171","msg":"trace[656291167] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"392.074914ms","start":"2026-04-07T05:53:52.011586Z","end":"2026-04-07T05:53:52.403661Z","steps":["trace[656291167] 'read index received' (duration: 39.781µs)","trace[656291167] 'applied index is now lower than readState.Index' (duration: 392.033913ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:53:52.403794Z","caller":"traceutil/trace.go:171","msg":"trace[903047558] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"876.61957ms","start":"2026-04-07T05:53:51.527162Z","end":"2026-04-07T05:53:52.403782Z","steps":["trace[903047558] 'process raft request' (duration: 33.198836ms)","trace[903047558] 'compare' (duration: 842.682981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:53:52.403857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:51.527139Z","time spent":"876.690833ms","remote":"127.0.0.1:48380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:53:52.404014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.398374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:53:52.404015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.099218ms","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-07T05:53:52.404106Z","caller":"traceutil/trace.go:171","msg":"trace[265627839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"392.538119ms","start":"2026-04-07T05:53:52.011543Z","end":"2026-04-07T05:53:52.404081Z","steps":["trace[265627839] 'agreement among raft nodes before linearized reading' (duration: 392.346693ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:53:52.404113Z","caller":"traceutil/trace.go:171","msg":"trace[379820431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"388.240102ms","start":"2026-04-07T05:53:52.01585Z","end":"2026-04-07T05:53:52.40409Z","steps":["trace[379820431] 'agreement among raft nodes before linearized reading' (duration: 388.022515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:53:52.404156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:52.01583Z","time spent":"388.317345ms","remote":"127.0.0.1:48128","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-07T05:53:52.404155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:53:52.011523Z","time spent":"392.623582ms","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T05:54:07.475718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.319264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vqzmc\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-07T05:54:07.475836Z","caller":"traceutil/trace.go:171","msg":"trace[471333705] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vqzmc; range_end:; response_count:1; response_revision:739; }","duration":"129.475719ms","start":"2026-04-07T05:54:07.346321Z","end":"2026-04-07T05:54:07.475796Z","steps":["trace[471333705] 'range keys from in-memory index tree' (duration: 129.077837ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:07.967309Z","caller":"traceutil/trace.go:171","msg":"trace[527900435] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"481.238541ms","start":"2026-04-07T05:54:07.486024Z","end":"2026-04-07T05:54:07.967263Z","steps":["trace[527900435] 'process raft request' (duration: 481.004554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:07.968313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:07.485998Z","time spent":"481.405467ms","remote":"127.0.0.1:48034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:18.767571Z","caller":"traceutil/trace.go:171","msg":"trace[242525776] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"103.471112ms","start":"2026-04-07T05:54:18.664078Z","end":"2026-04-07T05:54:18.767549Z","steps":["trace[242525776] 'process raft request' (duration: 103.325297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:36.962992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.359853ms","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-07T05:54:36.963089Z","caller":"traceutil/trace.go:171","msg":"trace[947011117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"115.517438ms","start":"2026-04-07T05:54:36.847554Z","end":"2026-04-07T05:54:36.963071Z","steps":["trace[947011117] 'range keys from in-memory index tree' (duration: 115.163577ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:36.963176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.38195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:54:36.963263Z","caller":"traceutil/trace.go:171","msg":"trace[894111167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"113.520625ms","start":"2026-04-07T05:54:36.849725Z","end":"2026-04-07T05:54:36.963246Z","steps":["trace[894111167] 'range keys from in-memory index tree' (duration: 113.188415ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:42.824644Z","caller":"traceutil/trace.go:171","msg":"trace[169625906] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"618.239647ms","start":"2026-04-07T05:54:42.206362Z","end":"2026-04-07T05:54:42.824602Z","steps":["trace[169625906] 'process raft request' (duration: 618.03375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:42.824767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:42.206344Z","time spent":"618.356691ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:43.221882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.098805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:54:43.221951Z","caller":"traceutil/trace.go:171","msg":"trace[836927987] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:984; }","duration":"527.047723ms","start":"2026-04-07T05:54:42.694893Z","end":"2026-04-07T05:54:43.221941Z","steps":["trace[836927987] 'read index received' (duration: 129.519745ms)","trace[836927987] 'applied index is now lower than readState.Index' (duration: 397.527178ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:43.222005Z","caller":"traceutil/trace.go:171","msg":"trace[2032201313] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"686.352006ms","start":"2026-04-07T05:54:42.535648Z","end":"2026-04-07T05:54:43.222Z","steps":["trace[2032201313] 'process raft request' (duration: 430.075065ms)","trace[2032201313] 'compare' (duration: 255.93385ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:43.222034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:42.535627Z","time spent":"686.391327ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:43.22216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.27298ms","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-07T05:54:43.222173Z","caller":"traceutil/trace.go:171","msg":"trace[510523575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"527.311361ms","start":"2026-04-07T05:54:42.694857Z","end":"2026-04-07T05:54:43.222169Z","steps":["trace[510523575] 'agreement among raft nodes before linearized reading' (duration: 527.237389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:43.222186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:42.694843Z","time spent":"527.339492ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:54:43.222243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.066735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:54:43.222254Z","caller":"traceutil/trace.go:171","msg":"trace[1064862878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"213.089255ms","start":"2026-04-07T05:54:43.009161Z","end":"2026-04-07T05:54:43.22225Z","steps":["trace[1064862878] 'agreement among raft nodes before linearized reading' (duration: 213.055564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:43.222327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.524577ms","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-07T05:54:43.222388Z","caller":"traceutil/trace.go:171","msg":"trace[357629113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:953; }","duration":"217.6105ms","start":"2026-04-07T05:54:43.004758Z","end":"2026-04-07T05:54:43.222369Z","steps":["trace[357629113] 'agreement among raft nodes before linearized reading' (duration: 217.442294ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:43.41889Z","caller":"traceutil/trace.go:171","msg":"trace[2056822493] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"192.918813ms","start":"2026-04-07T05:54:43.225944Z","end":"2026-04-07T05:54:43.418863Z","steps":["trace[2056822493] 'process raft request' (duration: 125.868238ms)","trace[2056822493] 'compare' (duration: 66.860189ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:44.218163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.365318ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:54:44.218354Z","caller":"traceutil/trace.go:171","msg":"trace[1461799944] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"991.515832ms","start":"2026-04-07T05:54:43.226815Z","end":"2026-04-07T05:54:44.218331Z","steps":["trace[1461799944] 'process raft request' (duration: 503.887816ms)","trace[1461799944] 'compare' (duration: 487.231093ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:44.218482Z","caller":"traceutil/trace.go:171","msg":"trace[570650447] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:986; }","duration":"945.240788ms","start":"2026-04-07T05:54:43.273188Z","end":"2026-04-07T05:54:44.218429Z","steps":["trace[570650447] 'read index received' (duration: 78.636604ms)","trace[570650447] 'applied index is now lower than readState.Index' (duration: 866.602594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:44.218518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:43.226792Z","time spent":"991.606394ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:44.218559Z","caller":"traceutil/trace.go:171","msg":"trace[1125175776] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"990.238751ms","start":"2026-04-07T05:54:43.228313Z","end":"2026-04-07T05:54:44.218552Z","steps":["trace[1125175776] 'process raft request' (duration: 989.954062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:44.218647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:43.228299Z","time spent":"990.279652ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:44.218862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.667922ms","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-07T05:54:44.218913Z","caller":"traceutil/trace.go:171","msg":"trace[1041871162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"945.744694ms","start":"2026-04-07T05:54:43.273154Z","end":"2026-04-07T05:54:44.218898Z","steps":["trace[1041871162] 'agreement among raft nodes before linearized reading' (duration: 945.569198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:44.218966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:43.27314Z","time spent":"945.799796ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T05:54:45.425289Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.003281427s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T05:54:45.842947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.420883265s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:54:45.843164Z","caller":"traceutil/trace.go:171","msg":"trace[2125008945] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.619528891s","start":"2026-04-07T05:54:44.223619Z","end":"2026-04-07T05:54:45.843148Z","steps":["trace[2125008945] 'process raft request' (duration: 1.619440058s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:45.843226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:44.223597Z","time spent":"1.619598664s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:54:45.843419Z","caller":"traceutil/trace.go:171","msg":"trace[1851538035] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.735515973s","start":"2026-04-07T05:54:44.107857Z","end":"2026-04-07T05:54:45.843373Z","steps":["trace[1851538035] 'process raft request' (duration: 314.104241ms)","trace[1851538035] 'compare' (duration: 1.420459832s)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:45.843443Z","caller":"traceutil/trace.go:171","msg":"trace[871720097] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"1.624884481s","start":"2026-04-07T05:54:44.218525Z","end":"2026-04-07T05:54:45.843409Z","steps":["trace[871720097] 'read index received' (duration: 203.511071ms)","trace[871720097] 'applied index is now lower than readState.Index' (duration: 1.42136628s)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:54:45.843521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:44.107831Z","time spent":"1.735639427s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:45.843613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.832990527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:54:45.843638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.389483097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:54:45.84368Z","caller":"traceutil/trace.go:171","msg":"trace[1535489363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"1.833112041s","start":"2026-04-07T05:54:44.010558Z","end":"2026-04-07T05:54:45.84367Z","steps":["trace[1535489363] 'agreement among raft nodes before linearized reading' (duration: 1.832984487s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:45.843701Z","caller":"traceutil/trace.go:171","msg":"trace[793562244] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:958; }","duration":"2.3895872s","start":"2026-04-07T05:54:43.454098Z","end":"2026-04-07T05:54:45.843685Z","steps":["trace[793562244] 'agreement among raft nodes before linearized reading' (duration: 2.389450586s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:45.84372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:44.010538Z","time spent":"1.833173283s","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T05:54:45.843735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:43.454082Z","time spent":"2.389645443s","remote":"127.0.0.1:48196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-07T05:54:45.845068Z","caller":"traceutil/trace.go:171","msg":"trace[1572091396] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"617.406441ms","start":"2026-04-07T05:54:45.227647Z","end":"2026-04-07T05:54:45.845053Z","steps":["trace[1572091396] 'process raft request' (duration: 617.294607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:45.845192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:45.227624Z","time spent":"617.491753ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:54:45.847747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.427405ms","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-07T05:54:45.847788Z","caller":"traceutil/trace.go:171","msg":"trace[1093563875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"424.505897ms","start":"2026-04-07T05:54:45.423272Z","end":"2026-04-07T05:54:45.847778Z","steps":["trace[1093563875] 'agreement among raft nodes before linearized reading' (duration: 424.396344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:45.847822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:45.423255Z","time spent":"424.55945ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:54:45.848164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013478042s","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-07T05:54:45.848219Z","caller":"traceutil/trace.go:171","msg":"trace[89492487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"1.013573495s","start":"2026-04-07T05:54:44.834633Z","end":"2026-04-07T05:54:45.848207Z","steps":["trace[89492487] 'agreement among raft nodes before linearized reading' (duration: 1.013442641s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:45.848255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:44.834614Z","time spent":"1.013633337s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T05:54:48.018219Z","caller":"traceutil/trace.go:171","msg":"trace[989486939] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"167.624708ms","start":"2026-04-07T05:54:47.850575Z","end":"2026-04-07T05:54:48.018199Z","steps":["trace[989486939] 'process raft request' (duration: 167.493604ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:48.035736Z","caller":"traceutil/trace.go:171","msg":"trace[1635750369] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:997; }","duration":"181.474159ms","start":"2026-04-07T05:54:47.854242Z","end":"2026-04-07T05:54:48.035716Z","steps":["trace[1635750369] 'read index received' (duration: 163.866568ms)","trace[1635750369] 'applied index is now lower than readState.Index' (duration: 17.606741ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:48.035804Z","caller":"traceutil/trace.go:171","msg":"trace[1252890589] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"184.472454ms","start":"2026-04-07T05:54:47.851296Z","end":"2026-04-07T05:54:48.035768Z","steps":["trace[1252890589] 'process raft request' (duration: 184.312029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:48.03591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.645064ms","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-07T05:54:48.035936Z","caller":"traceutil/trace.go:171","msg":"trace[2024487413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"181.703407ms","start":"2026-04-07T05:54:47.854225Z","end":"2026-04-07T05:54:48.035928Z","steps":["trace[2024487413] 'agreement among raft nodes before linearized reading' (duration: 181.583493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:48.036011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.809192ms","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-07T05:54:48.036064Z","caller":"traceutil/trace.go:171","msg":"trace[1256771860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"172.890685ms","start":"2026-04-07T05:54:47.863159Z","end":"2026-04-07T05:54:48.03605Z","steps":["trace[1256771860] 'agreement among raft nodes before linearized reading' (duration: 172.72588ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:54:48.224727Z","caller":"traceutil/trace.go:171","msg":"trace[1776721527] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"185.063023ms","start":"2026-04-07T05:54:48.039638Z","end":"2026-04-07T05:54:48.224701Z","steps":["trace[1776721527] 'process raft request' (duration: 92.3454ms)","trace[1776721527] 'compare' (duration: 92.377832ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:48.27148Z","caller":"traceutil/trace.go:171","msg":"trace[1456651391] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"220.398038ms","start":"2026-04-07T05:54:48.051066Z","end":"2026-04-07T05:54:48.271465Z","steps":["trace[1456651391] 'read index received' (duration: 80.929507ms)","trace[1456651391] 'applied index is now lower than readState.Index' (duration: 139.467831ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:54:48.271511Z","caller":"traceutil/trace.go:171","msg":"trace[2079744134] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"230.81852ms","start":"2026-04-07T05:54:48.040679Z","end":"2026-04-07T05:54:48.271498Z","steps":["trace[2079744134] 'process raft request' (duration: 230.694846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:48.271608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.524392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:54:48.271666Z","caller":"traceutil/trace.go:171","msg":"trace[2144160491] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:967; }","duration":"220.628596ms","start":"2026-04-07T05:54:48.051023Z","end":"2026-04-07T05:54:48.271652Z","steps":["trace[2144160491] 'agreement among raft nodes before linearized reading' (duration: 220.524302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:56.169667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:54:56.669801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:54:57.170928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:54:57.671209Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:54:58.011682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00044361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T05:54:58.01177Z","caller":"traceutil/trace.go:171","msg":"trace[1892721877] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000585174s","start":"2026-04-07T05:54:56.011169Z","end":"2026-04-07T05:54:58.011754Z","steps":["trace[1892721877] 'agreement among raft nodes before linearized reading' (duration: 2.000466221s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:54:58.011806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.011152Z","time spent":"2.000646787s","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 05:54:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T05:54:58.172301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:54:58.672967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:54:59.17363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:54:59.674044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:55:00.012416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000532442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T05:55:00.016703Z","caller":"traceutil/trace.go:171","msg":"trace[10580917] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00486905s","start":"2026-04-07T05:54:58.011818Z","end":"2026-04-07T05:55:00.016687Z","steps":["trace[10580917] 'agreement among raft nodes before linearized reading' (duration: 2.000551273s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.016754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:58.011803Z","time spent":"2.004937742s","remote":"127.0.0.1:47856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 05:55:00 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T05:55:00.090083Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.627091349s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T05:55:00.157671Z","caller":"traceutil/trace.go:171","msg":"trace[223113314] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"4.302549394s","start":"2026-04-07T05:54:55.855106Z","end":"2026-04-07T05:55:00.157655Z","steps":["trace[223113314] 'process raft request' (duration: 4.302347668s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.157743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.489046843s","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":"info","ts":"2026-04-07T05:55:00.157771Z","caller":"traceutil/trace.go:171","msg":"trace[1383159853] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:991; }","duration":"4.489131036s","start":"2026-04-07T05:54:55.668632Z","end":"2026-04-07T05:55:00.157763Z","steps":["trace[1383159853] 'agreement among raft nodes before linearized reading' (duration: 4.489072154s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.15777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:55.855088Z","time spent":"4.302633407s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:00.157791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:55.668563Z","time spent":"4.489222749s","remote":"127.0.0.1:48272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-04-07T05:55:00.157772Z","caller":"traceutil/trace.go:171","msg":"trace[1865633821] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"4.097219746s","start":"2026-04-07T05:54:56.060528Z","end":"2026-04-07T05:55:00.157748Z","steps":["trace[1865633821] 'process raft request' (duration: 4.097051501s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:00.15767Z","caller":"traceutil/trace.go:171","msg":"trace[653654647] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1024; }","duration":"4.48896079s","start":"2026-04-07T05:54:55.668694Z","end":"2026-04-07T05:55:00.157655Z","steps":["trace[653654647] 'read index received' (duration: 4.421576395s)","trace[653654647] 'applied index is now lower than readState.Index' (duration: 67.383785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:00.157871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.060508Z","time spent":"4.09731275s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:00.158114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.428492613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:00.158143Z","caller":"traceutil/trace.go:171","msg":"trace[240140291] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:991; }","duration":"3.428558505s","start":"2026-04-07T05:54:56.729576Z","end":"2026-04-07T05:55:00.158135Z","steps":["trace[240140291] 'agreement among raft nodes before linearized reading' (duration: 3.428514893s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.729556Z","time spent":"3.428601266s","remote":"127.0.0.1:39126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:00.158177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.09106869s","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-07T05:55:00.158225Z","caller":"traceutil/trace.go:171","msg":"trace[1332710157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"4.091156234s","start":"2026-04-07T05:54:56.067055Z","end":"2026-04-07T05:55:00.158211Z","steps":["trace[1332710157] 'agreement among raft nodes before linearized reading' (duration: 4.091010829s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.388053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:55:00.158264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.067034Z","time spent":"4.091219455s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T05:55:00.15828Z","caller":"traceutil/trace.go:171","msg":"trace[1668217091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"136.426444ms","start":"2026-04-07T05:55:00.021849Z","end":"2026-04-07T05:55:00.158275Z","steps":["trace[1668217091] 'agreement among raft nodes before linearized reading' (duration: 136.400253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.657412462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:55:00.158448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.192030529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:55:00.158444Z","caller":"traceutil/trace.go:171","msg":"trace[685100330] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:991; }","duration":"3.657504005s","start":"2026-04-07T05:54:56.500927Z","end":"2026-04-07T05:55:00.158431Z","steps":["trace[685100330] 'agreement among raft nodes before linearized reading' (duration: 3.657422792s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:00.158466Z","caller":"traceutil/trace.go:171","msg":"trace[1246047105] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:991; }","duration":"2.192079481s","start":"2026-04-07T05:54:57.966381Z","end":"2026-04-07T05:55:00.158461Z","steps":["trace[1246047105] 'agreement among raft nodes before linearized reading' (duration: 2.19203748s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:57.966363Z","time spent":"2.192114952s","remote":"127.0.0.1:48198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:00.158488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.500918Z","time spent":"3.657557576s","remote":"127.0.0.1:39064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:00.158588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22159959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:00.158605Z","caller":"traceutil/trace.go:171","msg":"trace[2057809219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"1.221644391s","start":"2026-04-07T05:54:58.936955Z","end":"2026-04-07T05:55:00.1586Z","steps":["trace[2057809219] 'agreement among raft nodes before linearized reading' (duration: 1.221618511s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:58.936938Z","time spent":"1.221680702s","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T05:55:00.158657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.793765321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:55:00.15868Z","caller":"traceutil/trace.go:171","msg":"trace[1273223111] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:991; }","duration":"2.793822083s","start":"2026-04-07T05:54:57.364851Z","end":"2026-04-07T05:55:00.158673Z","steps":["trace[1273223111] 'agreement among raft nodes before linearized reading' (duration: 2.793777011s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:57.364834Z","time spent":"2.793859293s","remote":"127.0.0.1:48324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:00.158752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.836134493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T05:55:00.158793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.905024156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T05:55:00.158772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135574683s","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-07T05:55:00.158812Z","caller":"traceutil/trace.go:171","msg":"trace[1031689341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:991; }","duration":"3.905072538s","start":"2026-04-07T05:54:56.253734Z","end":"2026-04-07T05:55:00.158806Z","steps":["trace[1031689341] 'agreement among raft nodes before linearized reading' (duration: 3.905004516s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.253717Z","time spent":"3.90510857s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T05:55:00.158791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227533568s","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-07T05:55:00.158838Z","caller":"traceutil/trace.go:171","msg":"trace[1727818529] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:991; }","duration":"2.135677315s","start":"2026-04-07T05:54:58.023144Z","end":"2026-04-07T05:55:00.158822Z","steps":["trace[1727818529] 'agreement among raft nodes before linearized reading' (duration: 2.135527541s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.838107436s","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-07T05:55:00.158888Z","caller":"traceutil/trace.go:171","msg":"trace[596420020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"3.838143787s","start":"2026-04-07T05:54:56.320737Z","end":"2026-04-07T05:55:00.158881Z","steps":["trace[596420020] 'agreement among raft nodes before linearized reading' (duration: 3.838083345s)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:00.158879Z","caller":"traceutil/trace.go:171","msg":"trace[759750962] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:991; }","duration":"1.227672203s","start":"2026-04-07T05:54:58.931185Z","end":"2026-04-07T05:55:00.158857Z","steps":["trace[759750962] 'agreement among raft nodes before linearized reading' (duration: 1.227492617s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.320728Z","time spent":"3.838174138s","remote":"127.0.0.1:48128","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-07T05:55:00.158919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.858566477s","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-07T05:55:00.158941Z","caller":"traceutil/trace.go:171","msg":"trace[1844009458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"3.858618739s","start":"2026-04-07T05:54:56.300315Z","end":"2026-04-07T05:55:00.158933Z","steps":["trace[1844009458] 'agreement among raft nodes before linearized reading' (duration: 3.858563447s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.158941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:58.931169Z","time spent":"1.227762486s","remote":"127.0.0.1:48346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:00.15896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.300294Z","time spent":"3.85866087s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T05:55:00.158892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:58.023138Z","time spent":"2.135739987s","remote":"127.0.0.1:48224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:00.158798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.04582727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:00.159059Z","caller":"traceutil/trace.go:171","msg":"trace[1748841223] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:991; }","duration":"4.04611929s","start":"2026-04-07T05:54:56.11293Z","end":"2026-04-07T05:55:00.159049Z","steps":["trace[1748841223] 'agreement among raft nodes before linearized reading' (duration: 4.04582884s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.159085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.112915Z","time spent":"4.046162131s","remote":"127.0.0.1:48258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-04-07T05:55:00.158793Z","caller":"traceutil/trace.go:171","msg":"trace[1889386742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"3.836214415s","start":"2026-04-07T05:54:56.322566Z","end":"2026-04-07T05:55:00.158781Z","steps":["trace[1889386742] 'agreement among raft nodes before linearized reading' (duration: 3.836102552s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:00.159114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:54:56.322555Z","time spent":"3.836552707s","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T05:55:06.6214Z","caller":"traceutil/trace.go:171","msg":"trace[642305565] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"385.684101ms","start":"2026-04-07T05:55:06.235689Z","end":"2026-04-07T05:55:06.621373Z","steps":["trace[642305565] 'read index received' (duration: 385.517146ms)","trace[642305565] 'applied index is now lower than readState.Index' (duration: 165.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:06.621563Z","caller":"traceutil/trace.go:171","msg":"trace[1226684933] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"444.620008ms","start":"2026-04-07T05:55:06.176912Z","end":"2026-04-07T05:55:06.621532Z","steps":["trace[1226684933] 'process raft request' (duration: 444.279858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:06.62168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.778173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T05:55:06.621693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.566226ms","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-07T05:55:06.621722Z","caller":"traceutil/trace.go:171","msg":"trace[117613280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"384.888516ms","start":"2026-04-07T05:55:06.236824Z","end":"2026-04-07T05:55:06.621712Z","steps":["trace[117613280] 'agreement among raft nodes before linearized reading' (duration: 384.738661ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:06.621729Z","caller":"traceutil/trace.go:171","msg":"trace[2005967253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"384.626528ms","start":"2026-04-07T05:55:06.237092Z","end":"2026-04-07T05:55:06.621719Z","steps":["trace[2005967253] 'agreement among raft nodes before linearized reading' (duration: 384.512795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:06.621755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:06.236802Z","time spent":"384.946138ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T05:55:06.621742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:06.176889Z","time spent":"444.754442ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:06.621762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:06.237084Z","time spent":"384.669009ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T05:55:06.621868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.770572ms","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-07T05:55:06.621909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.874236ms","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-07T05:55:06.621944Z","caller":"traceutil/trace.go:171","msg":"trace[1173375164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"384.868186ms","start":"2026-04-07T05:55:06.237059Z","end":"2026-04-07T05:55:06.621927Z","steps":["trace[1173375164] 'agreement among raft nodes before linearized reading' (duration: 384.523005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:06.621962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.271901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-07T05:55:06.62198Z","caller":"traceutil/trace.go:171","msg":"trace[773173098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"384.99854ms","start":"2026-04-07T05:55:06.236967Z","end":"2026-04-07T05:55:06.621966Z","steps":["trace[773173098] 'agreement among raft nodes before linearized reading' (duration: 384.806684ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:06.621992Z","caller":"traceutil/trace.go:171","msg":"trace[1357844902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"386.300422ms","start":"2026-04-07T05:55:06.235682Z","end":"2026-04-07T05:55:06.621983Z","steps":["trace[1357844902] 'agreement among raft nodes before linearized reading' (duration: 385.832757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:06.621993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:06.237051Z","time spent":"384.930398ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T05:55:06.622021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:06.236943Z","time spent":"385.072492ms","remote":"127.0.0.1:48128","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-07T05:55:06.622021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:06.235632Z","time spent":"386.382594ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T05:55:06.729085Z","caller":"traceutil/trace.go:171","msg":"trace[2042473130] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"102.959888ms","start":"2026-04-07T05:55:06.626101Z","end":"2026-04-07T05:55:06.729061Z","steps":["trace[2042473130] 'process raft request' (duration: 102.799743ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:06.729365Z","caller":"traceutil/trace.go:171","msg":"trace[1001911259] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"103.204096ms","start":"2026-04-07T05:55:06.626138Z","end":"2026-04-07T05:55:06.729342Z","steps":["trace[1001911259] 'process raft request' (duration: 102.889156ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:06.729553Z","caller":"traceutil/trace.go:171","msg":"trace[92453712] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"102.714ms","start":"2026-04-07T05:55:06.626823Z","end":"2026-04-07T05:55:06.729537Z","steps":["trace[92453712] 'process raft request' (duration: 102.666249ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:06.729601Z","caller":"traceutil/trace.go:171","msg":"trace[1385411389] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"102.788823ms","start":"2026-04-07T05:55:06.626773Z","end":"2026-04-07T05:55:06.729562Z","steps":["trace[1385411389] 'process raft request' (duration: 102.496814ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:08.729635Z","caller":"traceutil/trace.go:171","msg":"trace[1137670584] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"102.711581ms","start":"2026-04-07T05:55:08.6269Z","end":"2026-04-07T05:55:08.729611Z","steps":["trace[1137670584] 'process raft request' (duration: 102.336849ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:09.02611Z","caller":"traceutil/trace.go:171","msg":"trace[91793076] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"291.480482ms","start":"2026-04-07T05:55:08.734608Z","end":"2026-04-07T05:55:09.026088Z","steps":["trace[91793076] 'read index received' (duration: 291.357078ms)","trace[91793076] 'applied index is now lower than readState.Index' (duration: 122.424µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:09.026231Z","caller":"traceutil/trace.go:171","msg":"trace[1509133892] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"291.897055ms","start":"2026-04-07T05:55:08.734258Z","end":"2026-04-07T05:55:09.026155Z","steps":["trace[1509133892] 'process raft request' (duration: 291.695569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:09.026437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.809092ms","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-07T05:55:09.026546Z","caller":"traceutil/trace.go:171","msg":"trace[1605552947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"291.900275ms","start":"2026-04-07T05:55:08.734573Z","end":"2026-04-07T05:55:09.026473Z","steps":["trace[1605552947] 'agreement among raft nodes before linearized reading' (duration: 291.612446ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:09.03097Z","caller":"traceutil/trace.go:171","msg":"trace[1106092976] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"296.005166ms","start":"2026-04-07T05:55:08.734954Z","end":"2026-04-07T05:55:09.03096Z","steps":["trace[1106092976] 'process raft request' (duration: 295.733727ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:09.031063Z","caller":"traceutil/trace.go:171","msg":"trace[1596696036] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"294.650612ms","start":"2026-04-07T05:55:08.73638Z","end":"2026-04-07T05:55:09.031031Z","steps":["trace[1596696036] 'process raft request' (duration: 294.360403ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:09.031258Z","caller":"traceutil/trace.go:171","msg":"trace[2127879196] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"293.070822ms","start":"2026-04-07T05:55:08.738179Z","end":"2026-04-07T05:55:09.03125Z","steps":["trace[2127879196] 'process raft request' (duration: 292.582237ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:10.72398Z","caller":"traceutil/trace.go:171","msg":"trace[1625121249] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"160.350046ms","start":"2026-04-07T05:55:10.563605Z","end":"2026-04-07T05:55:10.723955Z","steps":["trace[1625121249] 'process raft request' (duration: 117.026156ms)","trace[1625121249] 'compare' (duration: 43.243898ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:11.192124Z","caller":"traceutil/trace.go:171","msg":"trace[963639517] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"456.652791ms","start":"2026-04-07T05:55:10.735443Z","end":"2026-04-07T05:55:11.192096Z","steps":["trace[963639517] 'read index received' (duration: 408.433656ms)","trace[963639517] 'applied index is now lower than readState.Index' (duration: 48.217895ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:11.192269Z","caller":"traceutil/trace.go:171","msg":"trace[1011193387] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"457.916411ms","start":"2026-04-07T05:55:10.734309Z","end":"2026-04-07T05:55:11.192225Z","steps":["trace[1011193387] 'process raft request' (duration: 409.5157ms)","trace[1011193387] 'compare' (duration: 48.147193ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:11.192379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.906709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:55:11.192474Z","caller":"traceutil/trace.go:171","msg":"trace[623380600] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1026; }","duration":"457.028903ms","start":"2026-04-07T05:55:10.735425Z","end":"2026-04-07T05:55:11.192454Z","steps":["trace[623380600] 'agreement among raft nodes before linearized reading' (duration: 456.837317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.192519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:10.735418Z","time spent":"457.090575ms","remote":"127.0.0.1:48350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:11.192605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:10.734292Z","time spent":"458.110278ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:11.243553Z","caller":"traceutil/trace.go:171","msg":"trace[1875844628] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"169.675733ms","start":"2026-04-07T05:55:11.073863Z","end":"2026-04-07T05:55:11.243539Z","steps":["trace[1875844628] 'process raft request' (duration: 169.492717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.243554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.753049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:11.243614Z","caller":"traceutil/trace.go:171","msg":"trace[754385676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"415.863702ms","start":"2026-04-07T05:55:10.827737Z","end":"2026-04-07T05:55:11.243601Z","steps":["trace[754385676] 'agreement among raft nodes before linearized reading' (duration: 415.740418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.243649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:10.827721Z","time spent":"415.920054ms","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T05:55:11.243639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.666943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:55:11.243694Z","caller":"traceutil/trace.go:171","msg":"trace[1658829575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"207.761946ms","start":"2026-04-07T05:55:11.035918Z","end":"2026-04-07T05:55:11.24368Z","steps":["trace[1658829575] 'agreement among raft nodes before linearized reading' (duration: 207.568049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.24369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.086748ms","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-07T05:55:11.243761Z","caller":"traceutil/trace.go:171","msg":"trace[600905433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"214.200541ms","start":"2026-04-07T05:55:11.029542Z","end":"2026-04-07T05:55:11.243743Z","steps":["trace[600905433] 'agreement among raft nodes before linearized reading' (duration: 213.969023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.243859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.971894ms","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-07T05:55:11.243905Z","caller":"traceutil/trace.go:171","msg":"trace[2135620292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"176.034805ms","start":"2026-04-07T05:55:11.067858Z","end":"2026-04-07T05:55:11.243893Z","steps":["trace[2135620292] 'agreement among raft nodes before linearized reading' (duration: 175.932172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.243951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.442278ms","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-07T05:55:11.243984Z","caller":"traceutil/trace.go:171","msg":"trace[565194283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"208.51279ms","start":"2026-04-07T05:55:11.035462Z","end":"2026-04-07T05:55:11.243975Z","steps":["trace[565194283] 'agreement among raft nodes before linearized reading' (duration: 208.430797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.243973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.428511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:11.24404Z","caller":"traceutil/trace.go:171","msg":"trace[1467617581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"232.514994ms","start":"2026-04-07T05:55:11.011513Z","end":"2026-04-07T05:55:11.244028Z","steps":["trace[1467617581] 'agreement among raft nodes before linearized reading' (duration: 232.426501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.244066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.898731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T05:55:11.24409Z","caller":"traceutil/trace.go:171","msg":"trace[974070868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"207.939571ms","start":"2026-04-07T05:55:11.036144Z","end":"2026-04-07T05:55:11.244083Z","steps":["trace[974070868] 'agreement among raft nodes before linearized reading' (duration: 207.851489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.244088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.13371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:55:11.244125Z","caller":"traceutil/trace.go:171","msg":"trace[983740411] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1027; }","duration":"253.201722ms","start":"2026-04-07T05:55:10.990913Z","end":"2026-04-07T05:55:11.244115Z","steps":["trace[983740411] 'agreement among raft nodes before linearized reading' (duration: 253.147851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.903543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792773823,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:55:11.958346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:11.578499Z","time spent":"379.841576ms","remote":"127.0.0.1:57060","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T05:55:11.961378Z","caller":"traceutil/trace.go:171","msg":"trace[137574576] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"713.716927ms","start":"2026-04-07T05:55:11.247626Z","end":"2026-04-07T05:55:11.961343Z","steps":["trace[137574576] 'process raft request' (duration: 713.447689ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:11.961511Z","caller":"traceutil/trace.go:171","msg":"trace[449968198] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"713.425218ms","start":"2026-04-07T05:55:11.247974Z","end":"2026-04-07T05:55:11.9614Z","steps":["trace[449968198] 'process raft request' (duration: 713.241682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.961546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:11.247607Z","time spent":"713.863621ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:11.961847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:11.24795Z","time spent":"713.685996ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:11.961924Z","caller":"traceutil/trace.go:171","msg":"trace[1127670187] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"713.016705ms","start":"2026-04-07T05:55:11.248885Z","end":"2026-04-07T05:55:11.961902Z","steps":["trace[1127670187] 'process raft request' (duration: 712.819739ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:11.961874Z","caller":"traceutil/trace.go:171","msg":"trace[1930554015] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"713.429118ms","start":"2026-04-07T05:55:11.248414Z","end":"2026-04-07T05:55:11.961843Z","steps":["trace[1930554015] 'process raft request' (duration: 713.199631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.962059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:11.248879Z","time spent":"713.103387ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:11.962066Z","caller":"traceutil/trace.go:171","msg":"trace[1914881560] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1068; }","duration":"559.437395ms","start":"2026-04-07T05:55:11.402612Z","end":"2026-04-07T05:55:11.96205Z","steps":["trace[1914881560] 'read index received' (duration: 558.431603ms)","trace[1914881560] 'applied index is now lower than readState.Index' (duration: 813.836µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:11.962144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.496276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:55:11.962199Z","caller":"traceutil/trace.go:171","msg":"trace[1123823769] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1032; }","duration":"559.63403ms","start":"2026-04-07T05:55:11.402553Z","end":"2026-04-07T05:55:11.962187Z","steps":["trace[1123823769] 'agreement among raft nodes before linearized reading' (duration: 559.469725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:11.962394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:11.402533Z","time spent":"559.830577ms","remote":"127.0.0.1:48400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:11.96265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:11.248406Z","time spent":"714.168761ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:18.234666Z","caller":"traceutil/trace.go:171","msg":"trace[484821999] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"138.399807ms","start":"2026-04-07T05:55:18.096236Z","end":"2026-04-07T05:55:18.234636Z","steps":["trace[484821999] 'process raft request' (duration: 133.263244ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:25.234836Z","caller":"traceutil/trace.go:171","msg":"trace[1056686841] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"114.95904ms","start":"2026-04-07T05:55:25.119847Z","end":"2026-04-07T05:55:25.234806Z","steps":["trace[1056686841] 'process raft request' (duration: 114.787665ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:25.234897Z","caller":"traceutil/trace.go:171","msg":"trace[1866787921] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"113.100431ms","start":"2026-04-07T05:55:25.121767Z","end":"2026-04-07T05:55:25.234868Z","steps":["trace[1866787921] 'process raft request' (duration: 112.969507ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:25.234952Z","caller":"traceutil/trace.go:171","msg":"trace[1388837148] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"105.062025ms","start":"2026-04-07T05:55:25.129872Z","end":"2026-04-07T05:55:25.234934Z","steps":["trace[1388837148] 'process raft request' (duration: 104.982663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:25.235128Z","caller":"traceutil/trace.go:171","msg":"trace[1854929398] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"111.030325ms","start":"2026-04-07T05:55:25.124062Z","end":"2026-04-07T05:55:25.235092Z","steps":["trace[1854929398] 'process raft request' (duration: 110.716005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:29.599861Z","caller":"traceutil/trace.go:171","msg":"trace[81476423] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"149.297284ms","start":"2026-04-07T05:55:29.450541Z","end":"2026-04-07T05:55:29.599838Z","steps":["trace[81476423] 'process raft request' (duration: 149.048166ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:31.128919Z","caller":"traceutil/trace.go:171","msg":"trace[843542800] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"145.436651ms","start":"2026-04-07T05:55:30.983459Z","end":"2026-04-07T05:55:31.128895Z","steps":["trace[843542800] 'process raft request' (duration: 145.300827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:31.634068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.614728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:55:31.634135Z","caller":"traceutil/trace.go:171","msg":"trace[543077292] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1152; }","duration":"433.733822ms","start":"2026-04-07T05:55:31.200386Z","end":"2026-04-07T05:55:31.634119Z","steps":["trace[543077292] 'count revisions from in-memory index tree' (duration: 433.514635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:31.634169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:31.200363Z","time spent":"433.797343ms","remote":"127.0.0.1:39106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-04-07T05:55:31.851521Z","caller":"traceutil/trace.go:171","msg":"trace[927776741] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1197; }","duration":"248.166893ms","start":"2026-04-07T05:55:31.603332Z","end":"2026-04-07T05:55:31.851498Z","steps":["trace[927776741] 'read index received' (duration: 248.016538ms)","trace[927776741] 'applied index is now lower than readState.Index' (duration: 149.245µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:31.851606Z","caller":"traceutil/trace.go:171","msg":"trace[32901718] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"539.085706ms","start":"2026-04-07T05:55:31.312492Z","end":"2026-04-07T05:55:31.851578Z","steps":["trace[32901718] 'process raft request' (duration: 538.870919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:31.851703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.361478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T05:55:31.851742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:31.312448Z","time spent":"539.218831ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:31.851768Z","caller":"traceutil/trace.go:171","msg":"trace[406342647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1153; }","duration":"248.468022ms","start":"2026-04-07T05:55:31.60328Z","end":"2026-04-07T05:55:31.851748Z","steps":["trace[406342647] 'agreement among raft nodes before linearized reading' (duration: 248.309937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:34.359642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792774104,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T05:55:34.540106Z","caller":"traceutil/trace.go:171","msg":"trace[1970647257] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"682.852254ms","start":"2026-04-07T05:55:33.857217Z","end":"2026-04-07T05:55:34.540069Z","steps":["trace[1970647257] 'process raft request' (duration: 682.515143ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:34.540079Z","caller":"traceutil/trace.go:171","msg":"trace[644236978] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"681.069788ms","start":"2026-04-07T05:55:33.858965Z","end":"2026-04-07T05:55:34.540035Z","steps":["trace[644236978] 'read index received' (duration: 680.597013ms)","trace[644236978] 'applied index is now lower than readState.Index' (duration: 470.925µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:34.540283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:33.857196Z","time spent":"682.998169ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:34.540371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.236127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:55:34.540358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.370327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:55:34.540418Z","caller":"traceutil/trace.go:171","msg":"trace[1060661072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"532.405363ms","start":"2026-04-07T05:55:34.007998Z","end":"2026-04-07T05:55:34.540404Z","steps":["trace[1060661072] 'agreement among raft nodes before linearized reading' (duration: 532.271019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:34.540418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.211307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T05:55:34.540436Z","caller":"traceutil/trace.go:171","msg":"trace[61277617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1160; }","duration":"681.478621ms","start":"2026-04-07T05:55:33.858938Z","end":"2026-04-07T05:55:34.540417Z","steps":["trace[61277617] 'agreement among raft nodes before linearized reading' (duration: 681.233373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:34.54047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:34.007966Z","time spent":"532.495016ms","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T05:55:34.540485Z","caller":"traceutil/trace.go:171","msg":"trace[1703450124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1160; }","duration":"146.32489ms","start":"2026-04-07T05:55:34.394144Z","end":"2026-04-07T05:55:34.540468Z","steps":["trace[1703450124] 'agreement among raft nodes before linearized reading' (duration: 146.145785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:34.54049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:33.85893Z","time spent":"681.550663ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T05:55:34.540527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.027009ms","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-07T05:55:34.540634Z","caller":"traceutil/trace.go:171","msg":"trace[738161060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1160; }","duration":"239.188324ms","start":"2026-04-07T05:55:34.301424Z","end":"2026-04-07T05:55:34.540613Z","steps":["trace[738161060] 'agreement among raft nodes before linearized reading' (duration: 238.902135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:34.540692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.409454ms","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-07T05:55:34.540721Z","caller":"traceutil/trace.go:171","msg":"trace[1790009769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1160; }","duration":"261.467476ms","start":"2026-04-07T05:55:34.279246Z","end":"2026-04-07T05:55:34.540713Z","steps":["trace[1790009769] 'agreement among raft nodes before linearized reading' (duration: 261.389084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:35.271849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.638901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:55:35.271945Z","caller":"traceutil/trace.go:171","msg":"trace[1358865823] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"726.345979ms","start":"2026-04-07T05:55:34.54558Z","end":"2026-04-07T05:55:35.271926Z","steps":["trace[1358865823] 'process raft request' (duration: 598.549473ms)","trace[1358865823] 'compare' (duration: 127.369542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:35.272002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:34.545554Z","time spent":"726.422801ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:35.273567Z","caller":"traceutil/trace.go:171","msg":"trace[847089497] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1205; }","duration":"348.864187ms","start":"2026-04-07T05:55:34.924688Z","end":"2026-04-07T05:55:35.273552Z","steps":["trace[847089497] 'read index received' (duration: 219.644357ms)","trace[847089497] 'applied index is now lower than readState.Index' (duration: 129.21897ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:35.273849Z","caller":"traceutil/trace.go:171","msg":"trace[279785729] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"727.861727ms","start":"2026-04-07T05:55:34.545975Z","end":"2026-04-07T05:55:35.273837Z","steps":["trace[279785729] 'process raft request' (duration: 727.378912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:35.273926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:34.545966Z","time spent":"727.921159ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:35.27407Z","caller":"traceutil/trace.go:171","msg":"trace[388438381] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"728.052843ms","start":"2026-04-07T05:55:34.546008Z","end":"2026-04-07T05:55:35.27406Z","steps":["trace[388438381] 'process raft request' (duration: 727.448874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:35.274131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:34.545974Z","time spent":"728.127325ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:35.274276Z","caller":"traceutil/trace.go:171","msg":"trace[1950797123] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"728.223679ms","start":"2026-04-07T05:55:34.546041Z","end":"2026-04-07T05:55:35.274264Z","steps":["trace[1950797123] 'process raft request' (duration: 727.447314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:35.274338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:34.546034Z","time spent":"728.27388ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:35.274475Z","caller":"traceutil/trace.go:171","msg":"trace[930542959] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"728.379923ms","start":"2026-04-07T05:55:34.546082Z","end":"2026-04-07T05:55:35.274462Z","steps":["trace[930542959] 'process raft request' (duration: 727.438173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:35.274546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:34.546076Z","time spent":"728.435754ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:35.274695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.006244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:35.274735Z","caller":"traceutil/trace.go:171","msg":"trace[1758110840] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1165; }","duration":"350.069845ms","start":"2026-04-07T05:55:34.924656Z","end":"2026-04-07T05:55:35.274726Z","steps":["trace[1758110840] 'agreement among raft nodes before linearized reading' (duration: 350.006793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:35.274764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:34.924642Z","time spent":"350.115447ms","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:35.274919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.133241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:35.274953Z","caller":"traceutil/trace.go:171","msg":"trace[620949033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"264.209684ms","start":"2026-04-07T05:55:35.010735Z","end":"2026-04-07T05:55:35.274945Z","steps":["trace[620949033] 'agreement among raft nodes before linearized reading' (duration: 264.163143ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:39.491Z","caller":"traceutil/trace.go:171","msg":"trace[354126321] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"160.289782ms","start":"2026-04-07T05:55:39.330689Z","end":"2026-04-07T05:55:39.490979Z","steps":["trace[354126321] 'process raft request' (duration: 160.133577ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:39.491557Z","caller":"traceutil/trace.go:171","msg":"trace[362884158] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"160.133327ms","start":"2026-04-07T05:55:39.331378Z","end":"2026-04-07T05:55:39.491511Z","steps":["trace[362884158] 'process raft request' (duration: 159.759526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:39.491454Z","caller":"traceutil/trace.go:171","msg":"trace[526709826] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"160.702335ms","start":"2026-04-07T05:55:39.330708Z","end":"2026-04-07T05:55:39.49141Z","steps":["trace[526709826] 'process raft request' (duration: 160.23011ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:44.787398Z","caller":"traceutil/trace.go:171","msg":"trace[695679870] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1239; }","duration":"142.431298ms","start":"2026-04-07T05:55:44.64495Z","end":"2026-04-07T05:55:44.787381Z","steps":["trace[695679870] 'read index received' (duration: 142.315965ms)","trace[695679870] 'applied index is now lower than readState.Index' (duration: 114.603µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:44.787463Z","caller":"traceutil/trace.go:171","msg":"trace[1874306049] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"205.82155ms","start":"2026-04-07T05:55:44.581612Z","end":"2026-04-07T05:55:44.787434Z","steps":["trace[1874306049] 'process raft request' (duration: 205.669015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:44.787502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.530491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:44.787543Z","caller":"traceutil/trace.go:171","msg":"trace[1218355391] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1193; }","duration":"142.619534ms","start":"2026-04-07T05:55:44.644911Z","end":"2026-04-07T05:55:44.787531Z","steps":["trace[1218355391] 'agreement among raft nodes before linearized reading' (duration: 142.539821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:45.767082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.392534ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T05:55:47.575885Z","caller":"traceutil/trace.go:171","msg":"trace[1504771415] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"216.58646ms","start":"2026-04-07T05:55:47.359279Z","end":"2026-04-07T05:55:47.575866Z","steps":["trace[1504771415] 'read index received' (duration: 216.438585ms)","trace[1504771415] 'applied index is now lower than readState.Index' (duration: 146.875µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:47.57599Z","caller":"traceutil/trace.go:171","msg":"trace[1386020773] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"219.870723ms","start":"2026-04-07T05:55:47.356112Z","end":"2026-04-07T05:55:47.575983Z","steps":["trace[1386020773] 'process raft request' (duration: 219.622576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:47.576313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.018864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T05:55:47.576389Z","caller":"traceutil/trace.go:171","msg":"trace[1303209556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"217.138248ms","start":"2026-04-07T05:55:47.359233Z","end":"2026-04-07T05:55:47.576371Z","steps":["trace[1303209556] 'agreement among raft nodes before linearized reading' (duration: 217.005263ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:47.738448Z","caller":"traceutil/trace.go:171","msg":"trace[1135450989] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"157.911588ms","start":"2026-04-07T05:55:47.580508Z","end":"2026-04-07T05:55:47.73842Z","steps":["trace[1135450989] 'process raft request' (duration: 143.813892ms)","trace[1135450989] 'compare' (duration: 13.898779ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:47.803091Z","caller":"traceutil/trace.go:171","msg":"trace[1641989848] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"222.445165ms","start":"2026-04-07T05:55:47.580621Z","end":"2026-04-07T05:55:47.803066Z","steps":["trace[1641989848] 'process raft request' (duration: 222.128965ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:47.803092Z","caller":"traceutil/trace.go:171","msg":"trace[902942575] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"222.397034ms","start":"2026-04-07T05:55:47.580661Z","end":"2026-04-07T05:55:47.803058Z","steps":["trace[902942575] 'process raft request' (duration: 222.306181ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:47.803276Z","caller":"traceutil/trace.go:171","msg":"trace[394170380] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1254; }","duration":"114.816676ms","start":"2026-04-07T05:55:47.688448Z","end":"2026-04-07T05:55:47.803265Z","steps":["trace[394170380] 'read index received' (duration: 35.905864ms)","trace[394170380] 'applied index is now lower than readState.Index' (duration: 78.909632ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:47.803388Z","caller":"traceutil/trace.go:171","msg":"trace[219823853] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"222.660612ms","start":"2026-04-07T05:55:47.580716Z","end":"2026-04-07T05:55:47.803377Z","steps":["trace[219823853] 'process raft request' (duration: 222.29538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:47.803465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.996282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q6dn9\" ","response":"range_response_count:1 size:10486"} {"level":"info","ts":"2026-04-07T05:55:47.803508Z","caller":"traceutil/trace.go:171","msg":"trace[83695048] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-q6dn9; range_end:; response_count:1; response_revision:1210; }","duration":"115.091595ms","start":"2026-04-07T05:55:47.688404Z","end":"2026-04-07T05:55:47.803496Z","steps":["trace[83695048] 'agreement among raft nodes before linearized reading' (duration: 114.906839ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:48.101105Z","caller":"traceutil/trace.go:171","msg":"trace[627753403] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"208.813954ms","start":"2026-04-07T05:55:47.892263Z","end":"2026-04-07T05:55:48.101077Z","steps":["trace[627753403] 'process raft request' (duration: 208.66249ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:48.101294Z","caller":"traceutil/trace.go:171","msg":"trace[23642550] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"210.726335ms","start":"2026-04-07T05:55:47.8904Z","end":"2026-04-07T05:55:48.101126Z","steps":["trace[23642550] 'process raft request' (duration: 210.327352ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:49.064775Z","caller":"traceutil/trace.go:171","msg":"trace[504552247] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"317.421265ms","start":"2026-04-07T05:55:48.747332Z","end":"2026-04-07T05:55:49.064753Z","steps":["trace[504552247] 'read index received' (duration: 317.26376ms)","trace[504552247] 'applied index is now lower than readState.Index' (duration: 156.085µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:49.064911Z","caller":"traceutil/trace.go:171","msg":"trace[665389596] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"741.300711ms","start":"2026-04-07T05:55:48.323574Z","end":"2026-04-07T05:55:49.064874Z","steps":["trace[665389596] 'process raft request' (duration: 740.854237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:49.064954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.59105ms","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-07T05:55:49.064982Z","caller":"traceutil/trace.go:171","msg":"trace[1629324691] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1215; }","duration":"317.689464ms","start":"2026-04-07T05:55:48.747283Z","end":"2026-04-07T05:55:49.064973Z","steps":["trace[1629324691] 'agreement among raft nodes before linearized reading' (duration: 317.57446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:49.065012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:48.74726Z","time spent":"317.742335ms","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:49.065056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:48.323557Z","time spent":"741.413544ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:49.569265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.133274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:55:49.569416Z","caller":"traceutil/trace.go:171","msg":"trace[1926025587] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"504.551454ms","start":"2026-04-07T05:55:49.064841Z","end":"2026-04-07T05:55:49.569393Z","steps":["trace[1926025587] 'read index received' (duration: 245.060419ms)","trace[1926025587] 'applied index is now lower than readState.Index' (duration: 259.489005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:49.569485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.642823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:49.569562Z","caller":"traceutil/trace.go:171","msg":"trace[258605530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"558.765737ms","start":"2026-04-07T05:55:49.010781Z","end":"2026-04-07T05:55:49.569546Z","steps":["trace[258605530] 'agreement among raft nodes before linearized reading' (duration: 558.634063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:49.569575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.144591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:55:49.56961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:49.010766Z","time spent":"558.835949ms","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T05:55:49.56963Z","caller":"traceutil/trace.go:171","msg":"trace[49195971] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1216; }","duration":"725.249535ms","start":"2026-04-07T05:55:48.844369Z","end":"2026-04-07T05:55:49.569618Z","steps":["trace[49195971] 'agreement among raft nodes before linearized reading' (duration: 725.125601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:49.569675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:48.844328Z","time spent":"725.337918ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:49.569705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.816397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-07T05:55:49.56978Z","caller":"traceutil/trace.go:171","msg":"trace[1257238280] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1216; }","duration":"500.939391ms","start":"2026-04-07T05:55:49.068826Z","end":"2026-04-07T05:55:49.569766Z","steps":["trace[1257238280] 'agreement among raft nodes before linearized reading' (duration: 500.760895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:49.569822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:49.06881Z","time spent":"501.004912ms","remote":"127.0.0.1:48248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-07T05:55:49.569454Z","caller":"traceutil/trace.go:171","msg":"trace[830027069] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"769.339527ms","start":"2026-04-07T05:55:48.800062Z","end":"2026-04-07T05:55:49.569402Z","steps":["trace[830027069] 'process raft request' (duration: 509.947335ms)","trace[830027069] 'compare' (duration: 258.68049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:49.570096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:48.800034Z","time spent":"769.967557ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T05:55:49.697298Z","caller":"traceutil/trace.go:171","msg":"trace[1807333225] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"117.084198ms","start":"2026-04-07T05:55:49.580191Z","end":"2026-04-07T05:55:49.697275Z","steps":["trace[1807333225] 'read index received' (duration: 111.860513ms)","trace[1807333225] 'applied index is now lower than readState.Index' (duration: 5.222675ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:49.697632Z","caller":"traceutil/trace.go:171","msg":"trace[795434937] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"123.419738ms","start":"2026-04-07T05:55:49.5742Z","end":"2026-04-07T05:55:49.697619Z","steps":["trace[795434937] 'process raft request' (duration: 117.870322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:49.697928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.720588ms","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-07T05:55:49.697985Z","caller":"traceutil/trace.go:171","msg":"trace[1420968807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"117.817231ms","start":"2026-04-07T05:55:49.580155Z","end":"2026-04-07T05:55:49.697973Z","steps":["trace[1420968807] 'agreement among raft nodes before linearized reading' (duration: 117.653156ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:49.881816Z","caller":"traceutil/trace.go:171","msg":"trace[639629652] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"131.279425ms","start":"2026-04-07T05:55:49.750509Z","end":"2026-04-07T05:55:49.881788Z","steps":["trace[639629652] 'process raft request' (duration: 109.016223ms)","trace[639629652] 'compare' (duration: 22.035005ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:50.961719Z","caller":"traceutil/trace.go:171","msg":"trace[1734767122] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"133.575668ms","start":"2026-04-07T05:55:50.828114Z","end":"2026-04-07T05:55:50.961689Z","steps":["trace[1734767122] 'read index received' (duration: 41.1905ms)","trace[1734767122] 'applied index is now lower than readState.Index' (duration: 92.383348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:50.961897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.758895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:50.961937Z","caller":"traceutil/trace.go:171","msg":"trace[364840980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"133.854128ms","start":"2026-04-07T05:55:50.828072Z","end":"2026-04-07T05:55:50.961926Z","steps":["trace[364840980] 'agreement among raft nodes before linearized reading' (duration: 133.717714ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:50.962044Z","caller":"traceutil/trace.go:171","msg":"trace[1843974362] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"393.187668ms","start":"2026-04-07T05:55:50.568802Z","end":"2026-04-07T05:55:50.961989Z","steps":["trace[1843974362] 'process raft request' (duration: 300.49376ms)","trace[1843974362] 'compare' (duration: 91.82612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:50.962291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:50.568768Z","time spent":"393.381374ms","remote":"127.0.0.1:47896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:51.464703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792774246,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T05:55:51.844713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.503478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:55:51.844879Z","caller":"traceutil/trace.go:171","msg":"trace[1843096224] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1272; }","duration":"880.555439ms","start":"2026-04-07T05:55:50.964312Z","end":"2026-04-07T05:55:51.844867Z","steps":["trace[1843096224] 'read index received' (duration: 669.846064ms)","trace[1843096224] 'applied index is now lower than readState.Index' (duration: 210.708715ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:55:51.844934Z","caller":"traceutil/trace.go:171","msg":"trace[1022723306] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"980.548826ms","start":"2026-04-07T05:55:50.864376Z","end":"2026-04-07T05:55:51.844924Z","steps":["trace[1022723306] 'process raft request' (duration: 769.78008ms)","trace[1022723306] 'compare' (duration: 210.395025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:55:51.844971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:50.864352Z","time spent":"980.600528ms","remote":"127.0.0.1:47936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T05:55:51.845166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.860358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-07T05:55:51.845187Z","caller":"traceutil/trace.go:171","msg":"trace[125299002] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1226; }","duration":"880.931871ms","start":"2026-04-07T05:55:50.964249Z","end":"2026-04-07T05:55:51.845181Z","steps":["trace[125299002] 'agreement among raft nodes before linearized reading' (duration: 880.880609ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:51.845173Z","caller":"traceutil/trace.go:171","msg":"trace[1527501131] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"499.301939ms","start":"2026-04-07T05:55:51.345854Z","end":"2026-04-07T05:55:51.845156Z","steps":["trace[1527501131] 'process raft request' (duration: 498.938047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:51.845198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.674248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:55:51.845206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:50.964225Z","time spent":"880.977262ms","remote":"127.0.0.1:48020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-04-07T05:55:51.84522Z","caller":"traceutil/trace.go:171","msg":"trace[632013660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"833.73806ms","start":"2026-04-07T05:55:51.011475Z","end":"2026-04-07T05:55:51.845213Z","steps":["trace[632013660] 'agreement among raft nodes before linearized reading' (duration: 833.704539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:51.845241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:51.011448Z","time spent":"833.788701ms","remote":"127.0.0.1:47856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T05:55:51.845214Z","caller":"traceutil/trace.go:171","msg":"trace[234945108] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"269.876413ms","start":"2026-04-07T05:55:51.575306Z","end":"2026-04-07T05:55:51.845182Z","steps":["trace[234945108] 'process raft request' (duration: 269.533202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:51.845252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:51.345838Z","time spent":"499.37047ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:55:51.845305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.432307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:55:51.84532Z","caller":"traceutil/trace.go:171","msg":"trace[1221323072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1226; }","duration":"502.448728ms","start":"2026-04-07T05:55:51.342866Z","end":"2026-04-07T05:55:51.845315Z","steps":["trace[1221323072] 'agreement among raft nodes before linearized reading' (duration: 502.425007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:51.845333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:51.342832Z","time spent":"502.497189ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:51.845372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.454996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T05:55:51.845401Z","caller":"traceutil/trace.go:171","msg":"trace[693781183] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1226; }","duration":"526.522328ms","start":"2026-04-07T05:55:51.31887Z","end":"2026-04-07T05:55:51.845393Z","steps":["trace[693781183] 'agreement among raft nodes before linearized reading' (duration: 526.453656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:51.845429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:51.318849Z","time spent":"526.5733ms","remote":"127.0.0.1:48282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T05:55:51.845517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.316462ms","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-07T05:55:51.845552Z","caller":"traceutil/trace.go:171","msg":"trace[841254501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1226; }","duration":"109.398855ms","start":"2026-04-07T05:55:51.736143Z","end":"2026-04-07T05:55:51.845542Z","steps":["trace[841254501] 'agreement among raft nodes before linearized reading' (duration: 109.24727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:54.145469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.483334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T05:55:54.145448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.606279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T05:55:54.145511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.630134ms","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-07T05:55:54.145543Z","caller":"traceutil/trace.go:171","msg":"trace[1052157789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1236; }","duration":"208.599257ms","start":"2026-04-07T05:55:53.936927Z","end":"2026-04-07T05:55:54.145527Z","steps":["trace[1052157789] 'range keys from in-memory index tree' (duration: 208.339379ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:54.145575Z","caller":"traceutil/trace.go:171","msg":"trace[1810281384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"133.795845ms","start":"2026-04-07T05:55:54.011753Z","end":"2026-04-07T05:55:54.145549Z","steps":["trace[1810281384] 'range keys from in-memory index tree' (duration: 133.509076ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:54.145582Z","caller":"traceutil/trace.go:171","msg":"trace[1420937087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"123.758058ms","start":"2026-04-07T05:55:54.021809Z","end":"2026-04-07T05:55:54.145567Z","steps":["trace[1420937087] 'range keys from in-memory index tree' (duration: 123.365016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:54.1456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.190481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T05:55:54.145687Z","caller":"traceutil/trace.go:171","msg":"trace[683493202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"130.323286ms","start":"2026-04-07T05:55:54.015343Z","end":"2026-04-07T05:55:54.145666Z","steps":["trace[683493202] 'range keys from in-memory index tree' (duration: 129.960184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:54.145469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.61529ms","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-07T05:55:54.14573Z","caller":"traceutil/trace.go:171","msg":"trace[1578968148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"254.949221ms","start":"2026-04-07T05:55:53.890773Z","end":"2026-04-07T05:55:54.145722Z","steps":["trace[1578968148] 'range keys from in-memory index tree' (duration: 254.352543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:54.145854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.49571ms","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-07T05:55:54.145876Z","caller":"traceutil/trace.go:171","msg":"trace[1594324254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"218.559402ms","start":"2026-04-07T05:55:53.927311Z","end":"2026-04-07T05:55:54.145871Z","steps":["trace[1594324254] 'range keys from in-memory index tree' (duration: 217.87518ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:55:59.237203Z","caller":"traceutil/trace.go:171","msg":"trace[299035141] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"113.599508ms","start":"2026-04-07T05:55:59.123581Z","end":"2026-04-07T05:55:59.23718Z","steps":["trace[299035141] 'process raft request' (duration: 113.439253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:55:59.52586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.757809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T05:55:59.525972Z","caller":"traceutil/trace.go:171","msg":"trace[738432353] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"282.034086ms","start":"2026-04-07T05:55:59.243919Z","end":"2026-04-07T05:55:59.525953Z","steps":["trace[738432353] 'process raft request' (duration: 113.05682ms)","trace[738432353] 'compare' (duration: 168.621335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:56:00.509845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277985792774375,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T05:56:00.574183Z","caller":"traceutil/trace.go:171","msg":"trace[2020093089] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1344; }","duration":"564.818138ms","start":"2026-04-07T05:56:00.009338Z","end":"2026-04-07T05:56:00.574156Z","steps":["trace[2020093089] 'read index received' (duration: 564.673143ms)","trace[2020093089] 'applied index is now lower than readState.Index' (duration: 143.834µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T05:56:00.574711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.321663ms","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-07T05:56:00.574851Z","caller":"traceutil/trace.go:171","msg":"trace[824873022] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1295; }","duration":"565.51545ms","start":"2026-04-07T05:56:00.009313Z","end":"2026-04-07T05:56:00.574828Z","steps":["trace[824873022] 'agreement among raft nodes before linearized reading' (duration: 565.289313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:00.574871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.318585ms","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-07T05:56:00.574894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.595163ms","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-07T05:56:00.574962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.619895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-07T05:56:00.574884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.457819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-07T05:56:00.574967Z","caller":"traceutil/trace.go:171","msg":"trace[1266371055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"338.689807ms","start":"2026-04-07T05:56:00.236257Z","end":"2026-04-07T05:56:00.574947Z","steps":["trace[1266371055] 'agreement among raft nodes before linearized reading' (duration: 338.522411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:00.574995Z","caller":"traceutil/trace.go:171","msg":"trace[1922737916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"370.683697ms","start":"2026-04-07T05:56:00.204302Z","end":"2026-04-07T05:56:00.574986Z","steps":["trace[1922737916] 'agreement among raft nodes before linearized reading' (duration: 370.622275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:00.575017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:56:00.204284Z","time spent":"370.727928ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T05:56:00.575019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:56:00.236239Z","time spent":"338.767938ms","remote":"127.0.0.1:48128","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-07T05:56:00.575005Z","caller":"traceutil/trace.go:171","msg":"trace[1104453528] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"644.04845ms","start":"2026-04-07T05:55:59.930944Z","end":"2026-04-07T05:56:00.574992Z","steps":["trace[1104453528] 'process raft request' (duration: 642.970016ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:00.575052Z","caller":"traceutil/trace.go:171","msg":"trace[480499133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"338.601123ms","start":"2026-04-07T05:56:00.236395Z","end":"2026-04-07T05:56:00.574996Z","steps":["trace[480499133] 'agreement among raft nodes before linearized reading' (duration: 338.351855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:00.575123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:56:00.236326Z","time spent":"338.788509ms","remote":"127.0.0.1:48128","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-07T05:56:00.575145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:55:59.930926Z","time spent":"644.147953ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T05:56:00.574877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.023439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-07T05:56:00.574744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.578456ms","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-07T05:56:00.575304Z","caller":"traceutil/trace.go:171","msg":"trace[1573565174] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1295; }","duration":"157.498364ms","start":"2026-04-07T05:56:00.417797Z","end":"2026-04-07T05:56:00.575295Z","steps":["trace[1573565174] 'agreement among raft nodes before linearized reading' (duration: 156.926325ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:00.57531Z","caller":"traceutil/trace.go:171","msg":"trace[1009153440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"335.156374ms","start":"2026-04-07T05:56:00.240136Z","end":"2026-04-07T05:56:00.575292Z","steps":["trace[1009153440] 'agreement among raft nodes before linearized reading' (duration: 334.456912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:00.575427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:56:00.240103Z","time spent":"335.307359ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T05:56:00.574979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:56:00.009303Z","time spent":"565.659414ms","remote":"127.0.0.1:48078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-07T05:56:00.574952Z","caller":"traceutil/trace.go:171","msg":"trace[1324325219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"338.419048ms","start":"2026-04-07T05:56:00.236513Z","end":"2026-04-07T05:56:00.574932Z","steps":["trace[1324325219] 'agreement among raft nodes before linearized reading' (duration: 338.280624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:00.575627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:56:00.236497Z","time spent":"339.075178ms","remote":"127.0.0.1:48128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T05:56:00.575908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.931411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T05:56:00.575971Z","caller":"traceutil/trace.go:171","msg":"trace[1861255567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"565.025354ms","start":"2026-04-07T05:56:00.010929Z","end":"2026-04-07T05:56:00.575954Z","steps":["trace[1861255567] 'agreement among raft nodes before linearized reading' (duration: 563.62266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:00.576068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T05:56:00.010911Z","time spent":"565.142367ms","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T05:56:14.757815Z","caller":"traceutil/trace.go:171","msg":"trace[384396996] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"107.231107ms","start":"2026-04-07T05:56:14.65056Z","end":"2026-04-07T05:56:14.757792Z","steps":["trace[384396996] 'process raft request' (duration: 106.674779ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:14.75804Z","caller":"traceutil/trace.go:171","msg":"trace[1299538477] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"107.385761ms","start":"2026-04-07T05:56:14.650645Z","end":"2026-04-07T05:56:14.75803Z","steps":["trace[1299538477] 'process raft request' (duration: 107.108882ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:56:14.758229Z","caller":"traceutil/trace.go:171","msg":"trace[2124452899] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1423; }","duration":"106.490663ms","start":"2026-04-07T05:56:14.651707Z","end":"2026-04-07T05:56:14.758198Z","steps":["trace[2124452899] 'read index received' (duration: 31.812425ms)","trace[2124452899] 'applied index is now lower than readState.Index' (duration: 74.675268ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:56:14.75836Z","caller":"traceutil/trace.go:171","msg":"trace[279314774] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"106.753992ms","start":"2026-04-07T05:56:14.651572Z","end":"2026-04-07T05:56:14.758326Z","steps":["trace[279314774] 'process raft request' (duration: 106.422121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:14.758437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.72481ms","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-07T05:56:14.758479Z","caller":"traceutil/trace.go:171","msg":"trace[763563128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"106.844214ms","start":"2026-04-07T05:56:14.651625Z","end":"2026-04-07T05:56:14.758469Z","steps":["trace[763563128] 'agreement among raft nodes before linearized reading' (duration: 106.678309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:27.063994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.422901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T05:56:27.064078Z","caller":"traceutil/trace.go:171","msg":"trace[1265827923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1430; }","duration":"251.571075ms","start":"2026-04-07T05:56:26.812487Z","end":"2026-04-07T05:56:27.064058Z","steps":["trace[1265827923] 'range keys from in-memory index tree' (duration: 251.171733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:27.064149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.360082ms","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-07T05:56:27.064207Z","caller":"traceutil/trace.go:171","msg":"trace[1765503416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1430; }","duration":"253.482555ms","start":"2026-04-07T05:56:26.81071Z","end":"2026-04-07T05:56:27.064193Z","steps":["trace[1765503416] 'range keys from in-memory index tree' (duration: 253.193687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:27.064361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.360781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T05:56:27.064492Z","caller":"traceutil/trace.go:171","msg":"trace[848093318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1430; }","duration":"253.503856ms","start":"2026-04-07T05:56:26.810964Z","end":"2026-04-07T05:56:27.064468Z","steps":["trace[848093318] 'range keys from in-memory index tree' (duration: 253.213127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:56:27.064776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.459434ms","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-07T05:56:27.064934Z","caller":"traceutil/trace.go:171","msg":"trace[1585992625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1430; }","duration":"259.686611ms","start":"2026-04-07T05:56:26.805227Z","end":"2026-04-07T05:56:27.064914Z","steps":["trace[1585992625] 'range keys from in-memory index tree' (duration: 259.238058ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:58:54.198308Z","caller":"traceutil/trace.go:171","msg":"trace[48557115] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2264; }","duration":"116.238117ms","start":"2026-04-07T05:58:54.082042Z","end":"2026-04-07T05:58:54.19828Z","steps":["trace[48557115] 'read index received' (duration: 35.605113ms)","trace[48557115] 'applied index is now lower than readState.Index' (duration: 80.631604ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:58:54.198451Z","caller":"traceutil/trace.go:171","msg":"trace[943712211] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"121.232034ms","start":"2026-04-07T05:58:54.077175Z","end":"2026-04-07T05:58:54.198407Z","steps":["trace[943712211] 'process raft request' (duration: 120.948035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T05:58:54.19875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.66866ms","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-07T05:58:54.199613Z","caller":"traceutil/trace.go:171","msg":"trace[1353192045] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2181; }","duration":"116.804135ms","start":"2026-04-07T05:58:54.082024Z","end":"2026-04-07T05:58:54.198828Z","steps":["trace[1353192045] 'agreement among raft nodes before linearized reading' (duration: 116.399912ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:58:54.363957Z","caller":"traceutil/trace.go:171","msg":"trace[610379732] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"159.708937ms","start":"2026-04-07T05:58:54.204222Z","end":"2026-04-07T05:58:54.363931Z","steps":["trace[610379732] 'process raft request' (duration: 112.815988ms)","trace[610379732] 'compare' (duration: 46.761605ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T05:58:58.997275Z","caller":"traceutil/trace.go:171","msg":"trace[476401085] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"128.139842ms","start":"2026-04-07T05:58:58.869106Z","end":"2026-04-07T05:58:58.997246Z","steps":["trace[476401085] 'process raft request' (duration: 128.017409ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T05:59:12.017973Z","caller":"traceutil/trace.go:171","msg":"trace[949111069] transaction","detail":"{read_only:false; response_revision:2293; number_of_response:1; }","duration":"180.287736ms","start":"2026-04-07T05:59:11.837651Z","end":"2026-04-07T05:59:12.017939Z","steps":["trace[949111069] 'process raft request' (duration: 178.19292ms)"],"step_count":1}