{"level":"warn","ts":"2026-05-20T03:02:20.032562Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:02:20.032653Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-20T03:02:20.032716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:02:20.03273Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-20T03:02:20.032745Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T03:02:20.033531Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-05-20T03:02:20.03369Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-20T03:02:20.03825Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.211248ms"} {"level":"info","ts":"2026-05-20T03:02:20.053059Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-05-20T03:02:20.053149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T03:02:20.053188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-05-20T03:02:20.053206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T03:02:20.053221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-05-20T03:02:20.05328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-05-20T03:02:20.063939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T03:02:20.065495Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T03:02:20.066642Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-20T03:02:20.068875Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T03:02:20.0691Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T03:02:20.069193Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:02:20.069264Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:02:20.069282Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:02:20.069955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-05-20T03:02:20.07056Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-20T03:02:20.073784Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T03:02:20.073882Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-20T03:02:20.073942Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-20T03:02:20.074065Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T03:02:20.074108Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T03:02:20.354677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T03:02:20.354773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T03:02:20.354796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-05-20T03:02:20.354816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-05-20T03:02:20.354835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-20T03:02:20.354851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-05-20T03:02:20.354872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-20T03:02:20.356472Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T03:02:20.356677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:02:20.356776Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:02:20.356909Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:02:20.357022Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T03:02:20.357075Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T03:02:20.357774Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:02:20.357839Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:02:20.357861Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:02:20.358908Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-05-20T03:02:20.359377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T03:02:22.663148Z","caller":"traceutil/trace.go:171","msg":"trace[580658070] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"105.896871ms","start":"2026-05-20T03:02:22.557225Z","end":"2026-05-20T03:02:22.663122Z","steps":["trace[580658070] 'read index received' (duration: 105.636368ms)","trace[580658070] 'applied index is now lower than readState.Index' (duration: 259.653µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:22.663262Z","caller":"traceutil/trace.go:171","msg":"trace[1925825530] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"106.064894ms","start":"2026-05-20T03:02:22.557154Z","end":"2026-05-20T03:02:22.663219Z","steps":["trace[1925825530] 'process raft request' (duration: 105.77707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:22.663529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.241765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-20T03:02:22.663752Z","caller":"traceutil/trace.go:171","msg":"trace[1116347699] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:113; }","duration":"106.531498ms","start":"2026-05-20T03:02:22.557191Z","end":"2026-05-20T03:02:22.663723Z","steps":["trace[1116347699] 'agreement among raft nodes before linearized reading' (duration: 106.139234ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:28.231101Z","caller":"traceutil/trace.go:171","msg":"trace[1653385250] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"365.885352ms","start":"2026-05-20T03:02:27.865188Z","end":"2026-05-20T03:02:28.231073Z","steps":["trace[1653385250] 'read index received' (duration: 365.68319ms)","trace[1653385250] 'applied index is now lower than readState.Index' (duration: 200.922µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:28.231264Z","caller":"traceutil/trace.go:171","msg":"trace[1599286644] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"392.173484ms","start":"2026-05-20T03:02:27.839038Z","end":"2026-05-20T03:02:28.231211Z","steps":["trace[1599286644] 'process raft request' (duration: 391.84264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:28.231378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.176645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:28.231526Z","caller":"traceutil/trace.go:171","msg":"trace[345449383] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:326; }","duration":"366.404029ms","start":"2026-05-20T03:02:27.8651Z","end":"2026-05-20T03:02:28.231504Z","steps":["trace[345449383] 'agreement among raft nodes before linearized reading' (duration: 366.172815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:28.231627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:27.86507Z","time spent":"366.53789ms","remote":"127.0.0.1:50018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"warn","ts":"2026-05-20T03:02:28.232351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:27.839003Z","time spent":"392.440386ms","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:02:28.731667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.338139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-20T03:02:28.731782Z","caller":"traceutil/trace.go:171","msg":"trace[546588138] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:331; }","duration":"216.51435ms","start":"2026-05-20T03:02:28.515242Z","end":"2026-05-20T03:02:28.731756Z","steps":["trace[546588138] 'range keys from in-memory index tree' (duration: 216.236787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:29.152262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.553124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:29.15255Z","caller":"traceutil/trace.go:171","msg":"trace[937522315] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:333; }","duration":"186.842677ms","start":"2026-05-20T03:02:28.965615Z","end":"2026-05-20T03:02:29.152458Z","steps":["trace[937522315] 'range keys from in-memory index tree' (duration: 186.446363ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:29.352219Z","caller":"traceutil/trace.go:171","msg":"trace[704790177] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"178.597414ms","start":"2026-05-20T03:02:29.173583Z","end":"2026-05-20T03:02:29.352181Z","steps":["trace[704790177] 'process raft request' (duration: 159.294513ms)","trace[704790177] 'compare' (duration: 19.098109ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:42.530767Z","caller":"traceutil/trace.go:171","msg":"trace[731383994] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"213.163849ms","start":"2026-05-20T03:02:42.317574Z","end":"2026-05-20T03:02:42.530738Z","steps":["trace[731383994] 'read index received' (duration: 212.893926ms)","trace[731383994] 'applied index is now lower than readState.Index' (duration: 268.963µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:42.530851Z","caller":"traceutil/trace.go:171","msg":"trace[655674951] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"224.618628ms","start":"2026-05-20T03:02:42.306186Z","end":"2026-05-20T03:02:42.530804Z","steps":["trace[655674951] 'process raft request' (duration: 224.398365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:42.530921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.32553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T03:02:42.530961Z","caller":"traceutil/trace.go:171","msg":"trace[50004173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"213.424272ms","start":"2026-05-20T03:02:42.317525Z","end":"2026-05-20T03:02:42.53095Z","steps":["trace[50004173] 'agreement among raft nodes before linearized reading' (duration: 213.32626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:44.071025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.02082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T03:02:44.071069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.043043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:44.071132Z","caller":"traceutil/trace.go:171","msg":"trace[338178232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:461; }","duration":"489.202754ms","start":"2026-05-20T03:02:43.581907Z","end":"2026-05-20T03:02:44.071109Z","steps":["trace[338178232] 'range keys from in-memory index tree' (duration: 488.90148ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:44.071135Z","caller":"traceutil/trace.go:171","msg":"trace[63395451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:461; }","duration":"235.180385ms","start":"2026-05-20T03:02:43.835938Z","end":"2026-05-20T03:02:44.071118Z","steps":["trace[63395451] 'range keys from in-memory index tree' (duration: 234.877111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:44.07112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.859191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-20T03:02:44.071185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:43.581883Z","time spent":"489.291984ms","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T03:02:44.07123Z","caller":"traceutil/trace.go:171","msg":"trace[1967887721] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:461; }","duration":"598.991423ms","start":"2026-05-20T03:02:43.472211Z","end":"2026-05-20T03:02:44.071202Z","steps":["trace[1967887721] 'range keys from in-memory index tree' (duration: 598.685668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:44.071304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:43.472143Z","time spent":"599.127474ms","remote":"127.0.0.1:49860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-05-20T03:02:44.07173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.893672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-05-20T03:02:44.071847Z","caller":"traceutil/trace.go:171","msg":"trace[388946028] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:461; }","duration":"128.083195ms","start":"2026-05-20T03:02:43.94374Z","end":"2026-05-20T03:02:44.071823Z","steps":["trace[388946028] 'range keys from in-memory index tree' (duration: 127.021213ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:44.227014Z","caller":"traceutil/trace.go:171","msg":"trace[495682965] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"151.511809ms","start":"2026-05-20T03:02:44.07546Z","end":"2026-05-20T03:02:44.226972Z","steps":["trace[495682965] 'process raft request' (duration: 151.236756ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:45.180501Z","caller":"traceutil/trace.go:171","msg":"trace[1384031082] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"141.433004ms","start":"2026-05-20T03:02:45.03904Z","end":"2026-05-20T03:02:45.180473Z","steps":["trace[1384031082] 'process raft request' (duration: 124.707615ms)","trace[1384031082] 'compare' (duration: 16.594967ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:46.168015Z","caller":"traceutil/trace.go:171","msg":"trace[304127749] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"333.070453ms","start":"2026-05-20T03:02:45.834917Z","end":"2026-05-20T03:02:46.167987Z","steps":["trace[304127749] 'read index received' (duration: 332.80695ms)","trace[304127749] 'applied index is now lower than readState.Index' (duration: 262.393µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:46.168104Z","caller":"traceutil/trace.go:171","msg":"trace[90846345] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"449.815268ms","start":"2026-05-20T03:02:45.718267Z","end":"2026-05-20T03:02:46.168082Z","steps":["trace[90846345] 'process raft request' (duration: 449.485655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:46.16819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.236735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:02:46.168251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:45.71824Z","time spent":"449.921299ms","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:02:46.168264Z","caller":"traceutil/trace.go:171","msg":"trace[432799837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"333.411917ms","start":"2026-05-20T03:02:45.83482Z","end":"2026-05-20T03:02:46.168232Z","steps":["trace[432799837] 'agreement among raft nodes before linearized reading' (duration: 333.294335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:46.168596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:45.834792Z","time spent":"333.68025ms","remote":"127.0.0.1:49820","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-05-20T03:02:47.452631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.913564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:02:47.453279Z","caller":"traceutil/trace.go:171","msg":"trace[1919454544] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"273.013222ms","start":"2026-05-20T03:02:47.180228Z","end":"2026-05-20T03:02:47.453241Z","steps":["trace[1919454544] 'process raft request' (duration: 93.216499ms)","trace[1919454544] 'compare' (duration: 178.489098ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:47.453774Z","caller":"traceutil/trace.go:171","msg":"trace[132687212] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"226.136095ms","start":"2026-05-20T03:02:47.227607Z","end":"2026-05-20T03:02:47.453743Z","steps":["trace[132687212] 'process raft request' (duration: 225.363096ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:47.453903Z","caller":"traceutil/trace.go:171","msg":"trace[1718122273] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"239.712358ms","start":"2026-05-20T03:02:47.214162Z","end":"2026-05-20T03:02:47.453874Z","steps":["trace[1718122273] 'process raft request' (duration: 238.659476ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:47.453903Z","caller":"traceutil/trace.go:171","msg":"trace[1189200364] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"201.69352ms","start":"2026-05-20T03:02:47.25205Z","end":"2026-05-20T03:02:47.453743Z","steps":["trace[1189200364] 'process raft request' (duration: 201.347996ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:48.041416Z","caller":"traceutil/trace.go:171","msg":"trace[1432758192] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"569.69135ms","start":"2026-05-20T03:02:47.471697Z","end":"2026-05-20T03:02:48.041388Z","steps":["trace[1432758192] 'process raft request' (duration: 569.606169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:48.041691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:47.471679Z","time spent":"569.887073ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:02:48.041664Z","caller":"traceutil/trace.go:171","msg":"trace[1368157927] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"580.847086ms","start":"2026-05-20T03:02:47.460655Z","end":"2026-05-20T03:02:48.041502Z","steps":["trace[1368157927] 'process raft request' (duration: 499.084387ms)","trace[1368157927] 'compare' (duration: 81.298445ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:48.041961Z","caller":"traceutil/trace.go:171","msg":"trace[1468132575] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"567.219022ms","start":"2026-05-20T03:02:47.474541Z","end":"2026-05-20T03:02:48.04176Z","steps":["trace[1468132575] 'process raft request' (duration: 566.826508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:48.042028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:47.474531Z","time spent":"567.470077ms","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:02:48.042128Z","caller":"traceutil/trace.go:171","msg":"trace[812574081] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"557.412542ms","start":"2026-05-20T03:02:47.484697Z","end":"2026-05-20T03:02:48.04211Z","steps":["trace[812574081] 'process raft request' (duration: 556.995716ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:48.042129Z","caller":"traceutil/trace.go:171","msg":"trace[2059720773] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:566; }","duration":"207.405524ms","start":"2026-05-20T03:02:47.834689Z","end":"2026-05-20T03:02:48.042095Z","steps":["trace[2059720773] 'read index received' (duration: 125.161798ms)","trace[2059720773] 'applied index is now lower than readState.Index' (duration: 82.242876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:02:48.042136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:47.460631Z","time spent":"581.345502ms","remote":"127.0.0.1:50080","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-05-20T03:02:48.042281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.571355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:48.04225Z","caller":"traceutil/trace.go:171","msg":"trace[322026634] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"516.071837ms","start":"2026-05-20T03:02:47.526107Z","end":"2026-05-20T03:02:48.042179Z","steps":["trace[322026634] 'process raft request' (duration: 515.928786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:48.042295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:47.484653Z","time spent":"557.527574ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:02:48.042324Z","caller":"traceutil/trace.go:171","msg":"trace[716633661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"207.683416ms","start":"2026-05-20T03:02:47.83463Z","end":"2026-05-20T03:02:48.042313Z","steps":["trace[716633661] 'agreement among raft nodes before linearized reading' (duration: 207.575205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:48.04243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:47.526071Z","time spent":"516.268579ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:02:49.205533Z","caller":"traceutil/trace.go:171","msg":"trace[397394690] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"370.559935ms","start":"2026-05-20T03:02:48.834949Z","end":"2026-05-20T03:02:49.205509Z","steps":["trace[397394690] 'read index received' (duration: 369.864737ms)","trace[397394690] 'applied index is now lower than readState.Index' (duration: 694.268µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:02:49.205697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.719667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:49.205671Z","caller":"traceutil/trace.go:171","msg":"trace[7480897] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"430.705881ms","start":"2026-05-20T03:02:48.77492Z","end":"2026-05-20T03:02:49.205626Z","steps":["trace[7480897] 'process raft request' (duration: 429.984383ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:49.205729Z","caller":"traceutil/trace.go:171","msg":"trace[220184687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"370.822848ms","start":"2026-05-20T03:02:48.834897Z","end":"2026-05-20T03:02:49.20572Z","steps":["trace[220184687] 'agreement among raft nodes before linearized reading' (duration: 370.727357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:49.205759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:48.834872Z","time spent":"370.879889ms","remote":"127.0.0.1:49818","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-05-20T03:02:49.205855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:48.774891Z","time spent":"430.864353ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:02:49.211143Z","caller":"traceutil/trace.go:171","msg":"trace[513465294] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"355.03306ms","start":"2026-05-20T03:02:48.856087Z","end":"2026-05-20T03:02:49.21112Z","steps":["trace[513465294] 'process raft request' (duration: 354.650135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:49.212003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:48.856058Z","time spent":"355.869649ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:02:49.487562Z","caller":"traceutil/trace.go:171","msg":"trace[1924883517] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"118.685304ms","start":"2026-05-20T03:02:49.36884Z","end":"2026-05-20T03:02:49.487525Z","steps":["trace[1924883517] 'read index received' (duration: 118.437371ms)","trace[1924883517] 'applied index is now lower than readState.Index' (duration: 246.583µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:02:49.487838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.954007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-05-20T03:02:49.487871Z","caller":"traceutil/trace.go:171","msg":"trace[1250918854] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:580; }","duration":"119.068518ms","start":"2026-05-20T03:02:49.36879Z","end":"2026-05-20T03:02:49.487858Z","steps":["trace[1250918854] 'agreement among raft nodes before linearized reading' (duration: 118.849195ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:49.491453Z","caller":"traceutil/trace.go:171","msg":"trace[56939385] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"128.732736ms","start":"2026-05-20T03:02:49.360907Z","end":"2026-05-20T03:02:49.48964Z","steps":["trace[56939385] 'process raft request' (duration: 125.172556ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:54.363115Z","caller":"traceutil/trace.go:171","msg":"trace[1920881199] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"125.749346ms","start":"2026-05-20T03:02:54.237339Z","end":"2026-05-20T03:02:54.363089Z","steps":["trace[1920881199] 'read index received' (duration: 124.938158ms)","trace[1920881199] 'applied index is now lower than readState.Index' (duration: 810.078µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:54.363347Z","caller":"traceutil/trace.go:171","msg":"trace[1288029364] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"148.748084ms","start":"2026-05-20T03:02:54.214588Z","end":"2026-05-20T03:02:54.363336Z","steps":["trace[1288029364] 'process raft request' (duration: 147.912995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:54.36375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.386864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k9t2d\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-20T03:02:54.363872Z","caller":"traceutil/trace.go:171","msg":"trace[320297906] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k9t2d; range_end:; response_count:1; response_revision:612; }","duration":"126.562038ms","start":"2026-05-20T03:02:54.237294Z","end":"2026-05-20T03:02:54.363856Z","steps":["trace[320297906] 'agreement among raft nodes before linearized reading' (duration: 126.385476ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:54.787115Z","caller":"traceutil/trace.go:171","msg":"trace[1885113651] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"392.794516ms","start":"2026-05-20T03:02:54.394291Z","end":"2026-05-20T03:02:54.787085Z","steps":["trace[1885113651] 'process raft request' (duration: 392.632785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:54.787281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:54.394265Z","time spent":"392.928068ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:02:55.665552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.593381ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:55.665737Z","caller":"traceutil/trace.go:171","msg":"trace[564863793] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:614; }","duration":"113.818863ms","start":"2026-05-20T03:02:55.551895Z","end":"2026-05-20T03:02:55.665713Z","steps":["trace[564863793] 'range keys from in-memory index tree' (duration: 113.568951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:55.665894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.357778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:02:55.666033Z","caller":"traceutil/trace.go:171","msg":"trace[585336372] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"1.07618443s","start":"2026-05-20T03:02:54.589819Z","end":"2026-05-20T03:02:55.666004Z","steps":["trace[585336372] 'read index received' (duration: 197.181868ms)","trace[585336372] 'applied index is now lower than readState.Index' (duration: 879.001282ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:55.666142Z","caller":"traceutil/trace.go:171","msg":"trace[2116052960] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.246992022s","start":"2026-05-20T03:02:54.419137Z","end":"2026-05-20T03:02:55.666129Z","steps":["trace[2116052960] 'process raft request' (duration: 854.306211ms)","trace[2116052960] 'compare' (duration: 392.092875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:02:55.666213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:54.419118Z","time spent":"1.247069492s","remote":"127.0.0.1:50326","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-05-20T03:02:55.666378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.434199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T03:02:55.666435Z","caller":"traceutil/trace.go:171","msg":"trace[1270767923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"965.528201ms","start":"2026-05-20T03:02:54.700892Z","end":"2026-05-20T03:02:55.66642Z","steps":["trace[1270767923] 'agreement among raft nodes before linearized reading' (duration: 965.288398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:55.666474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:54.700869Z","time spent":"965.594601ms","remote":"127.0.0.1:50080","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-05-20T03:02:55.666599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.076770897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:02:55.666635Z","caller":"traceutil/trace.go:171","msg":"trace[1568908681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"1.076865208s","start":"2026-05-20T03:02:54.58976Z","end":"2026-05-20T03:02:55.666626Z","steps":["trace[1568908681] 'agreement among raft nodes before linearized reading' (duration: 1.076757947s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:55.666668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:54.589733Z","time spent":"1.076930228s","remote":"127.0.0.1:50080","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-05-20T03:02:55.666689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.742424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:55.666729Z","caller":"traceutil/trace.go:171","msg":"trace[29303710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"823.815325ms","start":"2026-05-20T03:02:54.842904Z","end":"2026-05-20T03:02:55.666719Z","steps":["trace[29303710] 'agreement among raft nodes before linearized reading' (duration: 823.758804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:55.666762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:54.842881Z","time spent":"823.875825ms","remote":"127.0.0.1:49820","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-05-20T03:02:57.834701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999852518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-20T03:02:57.83483Z","caller":"traceutil/trace.go:171","msg":"trace[259686450] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00006811s","start":"2026-05-20T03:02:55.834728Z","end":"2026-05-20T03:02:57.834796Z","steps":["trace[259686450] 'agreement among raft nodes before linearized reading' (duration: 1.999903759s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:57.834968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:55.834697Z","time spent":"2.000252172s","remote":"127.0.0.1:49818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:02:58.077262Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.085259098s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-20T03:02:58.127747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135038514s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:02:58.128076Z","caller":"traceutil/trace.go:171","msg":"trace[883467480] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"2.452608015s","start":"2026-05-20T03:02:55.675447Z","end":"2026-05-20T03:02:58.128055Z","steps":["trace[883467480] 'process raft request' (duration: 2.452430503s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:58.12805Z","caller":"traceutil/trace.go:171","msg":"trace[1023761108] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"2.452568605s","start":"2026-05-20T03:02:55.675426Z","end":"2026-05-20T03:02:58.127995Z","steps":["trace[1023761108] 'process raft request' (duration: 316.46597ms)","trace[1023761108] 'compare' (duration: 2.134631858s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:02:58.128163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:55.675425Z","time spent":"2.452696506s","remote":"127.0.0.1:50080","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-05-20T03:02:58.128195Z","caller":"traceutil/trace.go:171","msg":"trace[1732860867] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"2.450227139s","start":"2026-05-20T03:02:55.67794Z","end":"2026-05-20T03:02:58.128167Z","steps":["trace[1732860867] 'read index received' (duration: 314.108934ms)","trace[1732860867] 'applied index is now lower than readState.Index' (duration: 2.136116695s)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:58.128234Z","caller":"traceutil/trace.go:171","msg":"trace[818487397] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"2.362786201s","start":"2026-05-20T03:02:55.765421Z","end":"2026-05-20T03:02:58.128207Z","steps":["trace[818487397] 'process raft request' (duration: 2.362571209s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:58.128279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:55.675404Z","time spent":"2.452740256s","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:02:58.128319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.450366091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:58.128369Z","caller":"traceutil/trace.go:171","msg":"trace[659433630] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"2.450438843s","start":"2026-05-20T03:02:55.677915Z","end":"2026-05-20T03:02:58.128353Z","steps":["trace[659433630] 'agreement among raft nodes before linearized reading' (duration: 2.450348741s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:58.128389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:55.765395Z","time spent":"2.362902503s","remote":"127.0.0.1:49996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4532,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:02:58.128409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:55.677906Z","time spent":"2.450495823s","remote":"127.0.0.1:50060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-20T03:02:58.138965Z","caller":"traceutil/trace.go:171","msg":"trace[1637737513] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.662461631s","start":"2026-05-20T03:02:56.476448Z","end":"2026-05-20T03:02:58.138909Z","steps":["trace[1637737513] 'process raft request' (duration: 1.662235268s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:58.139042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.065203783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-20T03:02:58.139133Z","caller":"traceutil/trace.go:171","msg":"trace[1822783918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"2.065331874s","start":"2026-05-20T03:02:56.073773Z","end":"2026-05-20T03:02:58.139105Z","steps":["trace[1822783918] 'agreement among raft nodes before linearized reading' (duration: 2.065098221s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:58.139186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:56.073761Z","time spent":"2.065416456s","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:02:58.139202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.735383436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:02:58.139252Z","caller":"traceutil/trace.go:171","msg":"trace[1448955634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"1.735492337s","start":"2026-05-20T03:02:56.403747Z","end":"2026-05-20T03:02:58.13924Z","steps":["trace[1448955634] 'agreement among raft nodes before linearized reading' (duration: 1.735294585s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:58.13918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.553409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:58.139349Z","caller":"traceutil/trace.go:171","msg":"trace[825258675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"303.784682ms","start":"2026-05-20T03:02:57.835534Z","end":"2026-05-20T03:02:58.139319Z","steps":["trace[825258675] 'agreement among raft nodes before linearized reading' (duration: 303.497619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:58.139349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:56.476403Z","time spent":"1.662674713s","remote":"127.0.0.1:50080","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-05-20T03:02:58.139409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:57.835508Z","time spent":"303.890533ms","remote":"127.0.0.1:49820","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-05-20T03:02:58.139284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:02:56.40372Z","time spent":"1.735558918s","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T03:02:58.271361Z","caller":"traceutil/trace.go:171","msg":"trace[454322891] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:641; }","duration":"125.570152ms","start":"2026-05-20T03:02:58.145765Z","end":"2026-05-20T03:02:58.271335Z","steps":["trace[454322891] 'read index received' (duration: 65.038366ms)","trace[454322891] 'applied index is now lower than readState.Index' (duration: 60.530666ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:02:58.271475Z","caller":"traceutil/trace.go:171","msg":"trace[984950300] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"127.029409ms","start":"2026-05-20T03:02:58.144437Z","end":"2026-05-20T03:02:58.271466Z","steps":["trace[984950300] 'process raft request' (duration: 126.851707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:02:58.271502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.705933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:02:58.271568Z","caller":"traceutil/trace.go:171","msg":"trace[506561157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"125.830444ms","start":"2026-05-20T03:02:58.145721Z","end":"2026-05-20T03:02:58.271552Z","steps":["trace[506561157] 'agreement among raft nodes before linearized reading' (duration: 125.720243ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:02:58.271792Z","caller":"traceutil/trace.go:171","msg":"trace[2013281548] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"128.189391ms","start":"2026-05-20T03:02:58.143427Z","end":"2026-05-20T03:02:58.271617Z","steps":["trace[2013281548] 'process raft request' (duration: 105.105133ms)","trace[2013281548] 'compare' (duration: 22.639393ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:00.273581Z","caller":"traceutil/trace.go:171","msg":"trace[393713888] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"247.305737ms","start":"2026-05-20T03:03:00.02625Z","end":"2026-05-20T03:03:00.273556Z","steps":["trace[393713888] 'read index received' (duration: 247.047914ms)","trace[393713888] 'applied index is now lower than readState.Index' (duration: 256.353µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:00.27364Z","caller":"traceutil/trace.go:171","msg":"trace[2041258849] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"265.650831ms","start":"2026-05-20T03:03:00.007932Z","end":"2026-05-20T03:03:00.273583Z","steps":["trace[2041258849] 'process raft request' (duration: 265.431299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.273782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.505429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.273839Z","caller":"traceutil/trace.go:171","msg":"trace[1009315536] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:637; }","duration":"247.616539ms","start":"2026-05-20T03:03:00.026205Z","end":"2026-05-20T03:03:00.273822Z","steps":["trace[1009315536] 'agreement among raft nodes before linearized reading' (duration: 247.505288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.274097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.467218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:03:00.274249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.330689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.274303Z","caller":"traceutil/trace.go:171","msg":"trace[308528907] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:637; }","duration":"223.383259ms","start":"2026-05-20T03:03:00.050901Z","end":"2026-05-20T03:03:00.274284Z","steps":["trace[308528907] 'agreement among raft nodes before linearized reading' (duration: 223.299738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.274356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.717211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.274394Z","caller":"traceutil/trace.go:171","msg":"trace[82639201] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:637; }","duration":"107.795281ms","start":"2026-05-20T03:03:00.166587Z","end":"2026-05-20T03:03:00.274382Z","steps":["trace[82639201] 'agreement among raft nodes before linearized reading' (duration: 107.737141ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:00.274451Z","caller":"traceutil/trace.go:171","msg":"trace[37787062] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:637; }","duration":"248.057984ms","start":"2026-05-20T03:03:00.026202Z","end":"2026-05-20T03:03:00.27426Z","steps":["trace[37787062] 'agreement among raft nodes before linearized reading' (duration: 247.418697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.274569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.640464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T03:03:00.274606Z","caller":"traceutil/trace.go:171","msg":"trace[1644462772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"137.711255ms","start":"2026-05-20T03:03:00.136887Z","end":"2026-05-20T03:03:00.274598Z","steps":["trace[1644462772] 'agreement among raft nodes before linearized reading' (duration: 137.573433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.27461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.04667ms","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":"info","ts":"2026-05-20T03:03:00.274653Z","caller":"traceutil/trace.go:171","msg":"trace[1623086065] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:637; }","duration":"131.094041ms","start":"2026-05-20T03:03:00.143545Z","end":"2026-05-20T03:03:00.274639Z","steps":["trace[1623086065] 'agreement among raft nodes before linearized reading' (duration: 131.04282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.274283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.065923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.27477Z","caller":"traceutil/trace.go:171","msg":"trace[264647437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:637; }","duration":"107.62942ms","start":"2026-05-20T03:03:00.167123Z","end":"2026-05-20T03:03:00.274752Z","steps":["trace[264647437] 'agreement among raft nodes before linearized reading' (duration: 107.014073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.274811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.903408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:03:00.274878Z","caller":"traceutil/trace.go:171","msg":"trace[182709546] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"138.002308ms","start":"2026-05-20T03:03:00.136865Z","end":"2026-05-20T03:03:00.274867Z","steps":["trace[182709546] 'agreement among raft nodes before linearized reading' (duration: 137.879117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.274246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.698377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:03:00.274306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.548619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.274959Z","caller":"traceutil/trace.go:171","msg":"trace[1678235798] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:637; }","duration":"108.217897ms","start":"2026-05-20T03:03:00.16673Z","end":"2026-05-20T03:03:00.274948Z","steps":["trace[1678235798] 'agreement among raft nodes before linearized reading' (duration: 107.546739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.274569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.843898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.274958Z","caller":"traceutil/trace.go:171","msg":"trace[1324531413] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:637; }","duration":"131.421225ms","start":"2026-05-20T03:03:00.143514Z","end":"2026-05-20T03:03:00.274935Z","steps":["trace[1324531413] 'agreement among raft nodes before linearized reading' (duration: 130.666316ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:00.275017Z","caller":"traceutil/trace.go:171","msg":"trace[1622254397] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:637; }","duration":"131.316884ms","start":"2026-05-20T03:03:00.143689Z","end":"2026-05-20T03:03:00.275006Z","steps":["trace[1622254397] 'agreement among raft nodes before linearized reading' (duration: 130.853008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.275176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.927768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:03:00.27518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.871386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.275217Z","caller":"traceutil/trace.go:171","msg":"trace[316941438] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:637; }","duration":"224.940407ms","start":"2026-05-20T03:03:00.050268Z","end":"2026-05-20T03:03:00.275209Z","steps":["trace[316941438] 'agreement among raft nodes before linearized reading' (duration: 224.860756ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:00.275216Z","caller":"traceutil/trace.go:171","msg":"trace[69737285] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:637; }","duration":"225.004558ms","start":"2026-05-20T03:03:00.050201Z","end":"2026-05-20T03:03:00.275205Z","steps":["trace[69737285] 'agreement among raft nodes before linearized reading' (duration: 224.940597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.277709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.744231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.277759Z","caller":"traceutil/trace.go:171","msg":"trace[255725407] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:637; }","duration":"251.337332ms","start":"2026-05-20T03:03:00.026404Z","end":"2026-05-20T03:03:00.277742Z","steps":["trace[255725407] 'agreement among raft nodes before linearized reading' (duration: 247.718971ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:00.438803Z","caller":"traceutil/trace.go:171","msg":"trace[1208670646] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"151.132064ms","start":"2026-05-20T03:03:00.287648Z","end":"2026-05-20T03:03:00.43878Z","steps":["trace[1208670646] 'read index received' (duration: 84.681543ms)","trace[1208670646] 'applied index is now lower than readState.Index' (duration: 66.449471ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:00.4391Z","caller":"traceutil/trace.go:171","msg":"trace[535140447] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"156.374904ms","start":"2026-05-20T03:03:00.282714Z","end":"2026-05-20T03:03:00.439089Z","steps":["trace[535140447] 'process raft request' (duration: 155.879878ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:00.439308Z","caller":"traceutil/trace.go:171","msg":"trace[1773260904] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"155.244671ms","start":"2026-05-20T03:03:00.284054Z","end":"2026-05-20T03:03:00.439298Z","steps":["trace[1773260904] 'process raft request' (duration: 154.647115ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:00.439418Z","caller":"traceutil/trace.go:171","msg":"trace[173567578] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"154.945526ms","start":"2026-05-20T03:03:00.284465Z","end":"2026-05-20T03:03:00.439411Z","steps":["trace[173567578] 'process raft request' (duration: 154.276969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.439644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.960653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.439689Z","caller":"traceutil/trace.go:171","msg":"trace[636888883] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:641; }","duration":"152.036134ms","start":"2026-05-20T03:03:00.287641Z","end":"2026-05-20T03:03:00.439678Z","steps":["trace[636888883] 'agreement among raft nodes before linearized reading' (duration: 151.865122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.439857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.150256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.439891Z","caller":"traceutil/trace.go:171","msg":"trace[2120984571] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:641; }","duration":"152.187487ms","start":"2026-05-20T03:03:00.287696Z","end":"2026-05-20T03:03:00.439883Z","steps":["trace[2120984571] 'agreement among raft nodes before linearized reading' (duration: 152.137475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.441013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.743504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.44106Z","caller":"traceutil/trace.go:171","msg":"trace[717401316] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:641; }","duration":"127.820535ms","start":"2026-05-20T03:03:00.313229Z","end":"2026-05-20T03:03:00.441049Z","steps":["trace[717401316] 'agreement among raft nodes before linearized reading' (duration: 127.759225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.441198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.884527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.44123Z","caller":"traceutil/trace.go:171","msg":"trace[458273466] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:641; }","duration":"128.919567ms","start":"2026-05-20T03:03:00.312303Z","end":"2026-05-20T03:03:00.441223Z","steps":["trace[458273466] 'agreement among raft nodes before linearized reading' (duration: 128.871546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.44134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.07921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.44138Z","caller":"traceutil/trace.go:171","msg":"trace[1626757229] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:641; }","duration":"129.15798ms","start":"2026-05-20T03:03:00.312206Z","end":"2026-05-20T03:03:00.441364Z","steps":["trace[1626757229] 'agreement among raft nodes before linearized reading' (duration: 129.11445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:00.44152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.767764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:00.441552Z","caller":"traceutil/trace.go:171","msg":"trace[812152480] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:641; }","duration":"153.832405ms","start":"2026-05-20T03:03:00.287712Z","end":"2026-05-20T03:03:00.441544Z","steps":["trace[812152480] 'agreement among raft nodes before linearized reading' (duration: 153.766495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:01.426869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.90665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:03:01.427004Z","caller":"traceutil/trace.go:171","msg":"trace[950238199] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"511.553249ms","start":"2026-05-20T03:03:00.915428Z","end":"2026-05-20T03:03:01.426981Z","steps":["trace[950238199] 'compare' (duration: 500.602137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:01.427063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:00.915413Z","time spent":"511.62756ms","remote":"127.0.0.1:49910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:03:01.739788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.90014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k9t2d\" ","response":"range_response_count:1 size:17786"} {"level":"info","ts":"2026-05-20T03:03:01.7399Z","caller":"traceutil/trace.go:171","msg":"trace[455684072] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k9t2d; range_end:; response_count:1; response_revision:645; }","duration":"141.076502ms","start":"2026-05-20T03:03:01.598802Z","end":"2026-05-20T03:03:01.739879Z","steps":["trace[455684072] 'range keys from in-memory index tree' (duration: 140.619107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:03.300999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.404245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:03:03.301146Z","caller":"traceutil/trace.go:171","msg":"trace[1722689893] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"449.629383ms","start":"2026-05-20T03:03:02.851483Z","end":"2026-05-20T03:03:03.301113Z","steps":["trace[1722689893] 'process raft request' (duration: 288.012786ms)","trace[1722689893] 'compare' (duration: 161.153302ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:03:03.301211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:02.85145Z","time spent":"449.736974ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:03:03.304461Z","caller":"traceutil/trace.go:171","msg":"trace[654129858] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"256.917268ms","start":"2026-05-20T03:03:03.047503Z","end":"2026-05-20T03:03:03.304421Z","steps":["trace[654129858] 'process raft request' (duration: 256.839157ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:03.304576Z","caller":"traceutil/trace.go:171","msg":"trace[1738887904] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"374.297545ms","start":"2026-05-20T03:03:02.930229Z","end":"2026-05-20T03:03:03.304527Z","steps":["trace[1738887904] 'process raft request' (duration: 373.92725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:03.304682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:02.930204Z","time spent":"374.434886ms","remote":"127.0.0.1:49910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:03:03.862017Z","caller":"traceutil/trace.go:171","msg":"trace[1587320759] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"137.041445ms","start":"2026-05-20T03:03:03.724937Z","end":"2026-05-20T03:03:03.861979Z","steps":["trace[1587320759] 'process raft request' (duration: 67.782884ms)","trace[1587320759] 'compare' (duration: 68.850096ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:03.981288Z","caller":"traceutil/trace.go:171","msg":"trace[1361365992] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"110.076105ms","start":"2026-05-20T03:03:03.871186Z","end":"2026-05-20T03:03:03.981262Z","steps":["trace[1361365992] 'process raft request' (duration: 97.171522ms)","trace[1361365992] 'compare' (duration: 12.782722ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:04.278729Z","caller":"traceutil/trace.go:171","msg":"trace[1425252719] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"286.729639ms","start":"2026-05-20T03:03:03.991972Z","end":"2026-05-20T03:03:04.278702Z","steps":["trace[1425252719] 'process raft request' (duration: 277.053921ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:04.473772Z","caller":"traceutil/trace.go:171","msg":"trace[1583317944] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:715; }","duration":"228.281948ms","start":"2026-05-20T03:03:04.245466Z","end":"2026-05-20T03:03:04.473748Z","steps":["trace[1583317944] 'read index received' (duration: 23.567751ms)","trace[1583317944] 'applied index is now lower than readState.Index' (duration: 204.713137ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:04.474044Z","caller":"traceutil/trace.go:171","msg":"trace[435474323] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"426.177989ms","start":"2026-05-20T03:03:04.047799Z","end":"2026-05-20T03:03:04.473977Z","steps":["trace[435474323] 'process raft request' (duration: 425.914646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:04.474153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:04.047753Z","time spent":"426.32319ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:03:04.474133Z","caller":"traceutil/trace.go:171","msg":"trace[1456523743] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"477.63255ms","start":"2026-05-20T03:03:03.996448Z","end":"2026-05-20T03:03:04.474081Z","steps":["trace[1456523743] 'process raft request' (duration: 477.076083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:04.474162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.664982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:04.474262Z","caller":"traceutil/trace.go:171","msg":"trace[290426081] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:694; }","duration":"228.853145ms","start":"2026-05-20T03:03:04.245385Z","end":"2026-05-20T03:03:04.474238Z","steps":["trace[290426081] 'agreement among raft nodes before linearized reading' (duration: 228.640183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:04.474317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:03.996416Z","time spent":"477.799462ms","remote":"127.0.0.1:49880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:03:04.712961Z","caller":"traceutil/trace.go:171","msg":"trace[926105711] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:718; }","duration":"239.087567ms","start":"2026-05-20T03:03:04.473841Z","end":"2026-05-20T03:03:04.712929Z","steps":["trace[926105711] 'read index received' (duration: 224.233602ms)","trace[926105711] 'applied index is now lower than readState.Index' (duration: 14.852805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:03:04.713114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.792756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:03:04.713171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.638231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:03:04.713171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.055236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T03:03:04.713207Z","caller":"traceutil/trace.go:171","msg":"trace[475681294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:695; }","duration":"254.731882ms","start":"2026-05-20T03:03:04.458465Z","end":"2026-05-20T03:03:04.713197Z","steps":["trace[475681294] 'agreement among raft nodes before linearized reading' (duration: 254.519598ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:04.713213Z","caller":"traceutil/trace.go:171","msg":"trace[869435462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:695; }","duration":"300.141678ms","start":"2026-05-20T03:03:04.413061Z","end":"2026-05-20T03:03:04.713203Z","steps":["trace[869435462] 'agreement among raft nodes before linearized reading' (duration: 300.005696ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:04.71321Z","caller":"traceutil/trace.go:171","msg":"trace[277624802] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:695; }","duration":"266.922028ms","start":"2026-05-20T03:03:04.446266Z","end":"2026-05-20T03:03:04.713188Z","steps":["trace[277624802] 'agreement among raft nodes before linearized reading' (duration: 266.761775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:04.713245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:04.413045Z","time spent":"300.191258ms","remote":"127.0.0.1:50080","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-05-20T03:03:04.713359Z","caller":"traceutil/trace.go:171","msg":"trace[880901009] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"255.188797ms","start":"2026-05-20T03:03:04.458159Z","end":"2026-05-20T03:03:04.713348Z","steps":["trace[880901009] 'process raft request' (duration: 239.901167ms)","trace[880901009] 'compare' (duration: 14.715033ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:03:04.713464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.345318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T03:03:04.713517Z","caller":"traceutil/trace.go:171","msg":"trace[483824030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:695; }","duration":"254.413379ms","start":"2026-05-20T03:03:04.459089Z","end":"2026-05-20T03:03:04.713502Z","steps":["trace[483824030] 'agreement among raft nodes before linearized reading' (duration: 254.265737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:04.713714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.867494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:03:04.713907Z","caller":"traceutil/trace.go:171","msg":"trace[753657107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:695; }","duration":"255.386879ms","start":"2026-05-20T03:03:04.458496Z","end":"2026-05-20T03:03:04.713883Z","steps":["trace[753657107] 'agreement among raft nodes before linearized reading' (duration: 254.484558ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:04.749776Z","caller":"traceutil/trace.go:171","msg":"trace[2110427826] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"268.673947ms","start":"2026-05-20T03:03:04.481049Z","end":"2026-05-20T03:03:04.749723Z","steps":["trace[2110427826] 'process raft request' (duration: 268.460285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:04.749847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.160725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T03:03:04.749882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.953406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-20T03:03:04.749912Z","caller":"traceutil/trace.go:171","msg":"trace[811000443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"271.271676ms","start":"2026-05-20T03:03:04.478623Z","end":"2026-05-20T03:03:04.749895Z","steps":["trace[811000443] 'agreement among raft nodes before linearized reading' (duration: 271.065624ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:04.749922Z","caller":"traceutil/trace.go:171","msg":"trace[968663608] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:696; }","duration":"104.061096ms","start":"2026-05-20T03:03:04.645849Z","end":"2026-05-20T03:03:04.749911Z","steps":["trace[968663608] 'agreement among raft nodes before linearized reading' (duration: 103.939575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:04.749931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.231361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k9t2d\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-05-20T03:03:04.750023Z","caller":"traceutil/trace.go:171","msg":"trace[561541708] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k9t2d; range_end:; response_count:1; response_revision:696; }","duration":"116.365563ms","start":"2026-05-20T03:03:04.633645Z","end":"2026-05-20T03:03:04.75001Z","steps":["trace[561541708] 'agreement among raft nodes before linearized reading' (duration: 116.189282ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:05.964686Z","caller":"traceutil/trace.go:171","msg":"trace[1234476275] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"128.589218ms","start":"2026-05-20T03:03:05.836069Z","end":"2026-05-20T03:03:05.964658Z","steps":["trace[1234476275] 'read index received' (duration: 125.920409ms)","trace[1234476275] 'applied index is now lower than readState.Index' (duration: 2.667379ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:05.964726Z","caller":"traceutil/trace.go:171","msg":"trace[203543462] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"306.241942ms","start":"2026-05-20T03:03:05.658442Z","end":"2026-05-20T03:03:05.964684Z","steps":["trace[203543462] 'process raft request' (duration: 303.578103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:05.964868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.777901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:05.964907Z","caller":"traceutil/trace.go:171","msg":"trace[433429718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"128.884002ms","start":"2026-05-20T03:03:05.836013Z","end":"2026-05-20T03:03:05.964897Z","steps":["trace[433429718] 'agreement among raft nodes before linearized reading' (duration: 128.791261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:05.964965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:05.65842Z","time spent":"306.419185ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:03:05.96523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.792801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-20T03:03:05.965332Z","caller":"traceutil/trace.go:171","msg":"trace[764527161] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"119.932503ms","start":"2026-05-20T03:03:05.845376Z","end":"2026-05-20T03:03:05.965309Z","steps":["trace[764527161] 'agreement among raft nodes before linearized reading' (duration: 119.679121ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:06.392625Z","caller":"traceutil/trace.go:171","msg":"trace[1487517465] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"108.808558ms","start":"2026-05-20T03:03:06.283792Z","end":"2026-05-20T03:03:06.3926Z","steps":["trace[1487517465] 'process raft request' (duration: 108.628666ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:06.57164Z","caller":"traceutil/trace.go:171","msg":"trace[168161630] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"116.602885ms","start":"2026-05-20T03:03:06.455014Z","end":"2026-05-20T03:03:06.571617Z","steps":["trace[168161630] 'process raft request' (duration: 116.192651ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:06.579986Z","caller":"traceutil/trace.go:171","msg":"trace[159273004] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"105.141397ms","start":"2026-05-20T03:03:06.474824Z","end":"2026-05-20T03:03:06.579965Z","steps":["trace[159273004] 'process raft request' (duration: 104.995436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:16.828871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.658165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:03:16.828986Z","caller":"traceutil/trace.go:171","msg":"trace[682775081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"994.821116ms","start":"2026-05-20T03:03:15.834141Z","end":"2026-05-20T03:03:16.828962Z","steps":["trace[682775081] 'range keys from in-memory index tree' (duration: 994.552713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:16.82904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:15.83412Z","time spent":"994.904977ms","remote":"127.0.0.1:49818","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-05-20T03:03:47.119098Z","caller":"traceutil/trace.go:171","msg":"trace[868468666] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"102.696109ms","start":"2026-05-20T03:03:47.016373Z","end":"2026-05-20T03:03:47.119069Z","steps":["trace[868468666] 'read index received' (duration: 102.507827ms)","trace[868468666] 'applied index is now lower than readState.Index' (duration: 187.622µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:03:47.119437Z","caller":"traceutil/trace.go:171","msg":"trace[1497225267] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"107.134737ms","start":"2026-05-20T03:03:47.012263Z","end":"2026-05-20T03:03:47.119398Z","steps":["trace[1497225267] 'process raft request' (duration: 106.693482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:47.11948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.087113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T03:03:47.119568Z","caller":"traceutil/trace.go:171","msg":"trace[1259885884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"103.210604ms","start":"2026-05-20T03:03:47.016338Z","end":"2026-05-20T03:03:47.119549Z","steps":["trace[1259885884] 'agreement among raft nodes before linearized reading' (duration: 103.017972ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:03:48.420443Z","caller":"traceutil/trace.go:171","msg":"trace[1444334042] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"522.126431ms","start":"2026-05-20T03:03:47.898295Z","end":"2026-05-20T03:03:48.420421Z","steps":["trace[1444334042] 'process raft request' (duration: 521.990779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:03:48.42059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:03:47.898266Z","time spent":"522.249832ms","remote":"127.0.0.1:50080","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-05-20T03:03:54.08915Z","caller":"traceutil/trace.go:171","msg":"trace[137636527] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"169.541165ms","start":"2026-05-20T03:03:53.919571Z","end":"2026-05-20T03:03:54.089112Z","steps":["trace[137636527] 'process raft request' (duration: 169.305832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:04:09.055035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.316225ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-20T03:04:09.515834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.851053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:04:09.516003Z","caller":"traceutil/trace.go:171","msg":"trace[1622834796] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1176; }","duration":"187.888426ms","start":"2026-05-20T03:04:09.328099Z","end":"2026-05-20T03:04:09.515987Z","steps":["trace[1622834796] 'read index received' (duration: 62.77542ms)","trace[1622834796] 'applied index is now lower than readState.Index' (duration: 125.112076ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:04:09.516092Z","caller":"traceutil/trace.go:171","msg":"trace[1387044165] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"194.339424ms","start":"2026-05-20T03:04:09.321741Z","end":"2026-05-20T03:04:09.51608Z","steps":["trace[1387044165] 'process raft request' (duration: 194.196183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:04:09.516365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.956671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T03:04:09.516493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.356481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T03:04:09.516602Z","caller":"traceutil/trace.go:171","msg":"trace[1796030821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1137; }","duration":"188.521513ms","start":"2026-05-20T03:04:09.328058Z","end":"2026-05-20T03:04:09.51658Z","steps":["trace[1796030821] 'agreement among raft nodes before linearized reading' (duration: 188.210078ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:04:09.516845Z","caller":"traceutil/trace.go:171","msg":"trace[1592376588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"145.044862ms","start":"2026-05-20T03:04:09.371364Z","end":"2026-05-20T03:04:09.516409Z","steps":["trace[1592376588] 'agreement among raft nodes before linearized reading' (duration: 144.817598ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:04:09.516549Z","caller":"traceutil/trace.go:171","msg":"trace[1967498449] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"194.431126ms","start":"2026-05-20T03:04:09.321679Z","end":"2026-05-20T03:04:09.51611Z","steps":["trace[1967498449] 'process raft request' (duration: 69.215039ms)","trace[1967498449] 'compare' (duration: 124.740942ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:04:09.709966Z","caller":"traceutil/trace.go:171","msg":"trace[1532244582] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"188.064249ms","start":"2026-05-20T03:04:09.521879Z","end":"2026-05-20T03:04:09.709943Z","steps":["trace[1532244582] 'process raft request' (duration: 187.913367ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:04:26.902272Z","caller":"traceutil/trace.go:171","msg":"trace[1526942582] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"185.075648ms","start":"2026-05-20T03:04:26.71717Z","end":"2026-05-20T03:04:26.902245Z","steps":["trace[1526942582] 'process raft request' (duration: 184.900966ms)"],"step_count":1}