{"level":"warn","ts":"2026-08-18T23:19:17.762373Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:19:17.762486Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-08-18T23:19:17.76257Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-18T23:19:17.762587Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-18T23:19:17.762605Z","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-08-18T23:19:17.763541Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-08-18T23:19:17.763639Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-08-18T23:19:17.775894Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.986725ms"} {"level":"info","ts":"2026-08-18T23:19:17.813061Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-08-18T23:19:17.813271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-08-18T23:19:17.813357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-08-18T23:19:17.813424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-18T23:19:17.813477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-08-18T23:19:17.813567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-08-18T23:19:17.897044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-18T23:19:17.940096Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-18T23:19:17.941816Z","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-08-18T23:19:17.94781Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-18T23:19:17.950376Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-18T23:19:17.95088Z","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-08-18T23:19:17.951046Z","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-08-18T23:19:17.95108Z","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-08-18T23:19:17.953237Z","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-08-18T23:19:17.953443Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-18T23:19:17.95349Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-18T23:19:17.954984Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-18T23:19:17.95502Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-08-18T23:19:17.958476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-08-18T23:19:17.958575Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-08-18T23:19:18.514512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-08-18T23:19:18.514582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-18T23:19:18.515001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-08-18T23:19:18.51504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.515135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.515205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.515405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-08-18T23:19:18.522314Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:18.523645Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:19:18.523645Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-08-18T23:19:18.523698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-18T23:19:18.524082Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-18T23:19:18.524134Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-18T23:19:18.526981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-08-18T23:19:18.527164Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-18T23:19:18.53708Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:18.537213Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:18.53725Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-18T23:19:22.530278Z","caller":"traceutil/trace.go:171","msg":"trace[1347142430] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"181.013068ms","start":"2026-08-18T23:19:22.349242Z","end":"2026-08-18T23:19:22.530255Z","steps":["trace[1347142430] 'process raft request' (duration: 180.895374ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:23.259045Z","caller":"traceutil/trace.go:171","msg":"trace[2126010546] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"102.659376ms","start":"2026-08-18T23:19:23.156358Z","end":"2026-08-18T23:19:23.259018Z","steps":["trace[2126010546] 'process raft request' (duration: 100.20043ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:25.165839Z","caller":"traceutil/trace.go:171","msg":"trace[1661007048] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"107.940083ms","start":"2026-08-18T23:19:25.057852Z","end":"2026-08-18T23:19:25.165792Z","steps":["trace[1661007048] 'process raft request' (duration: 52.837823ms)","trace[1661007048] 'compare' (duration: 54.81916ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:25.342532Z","caller":"traceutil/trace.go:171","msg":"trace[1986321799] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"115.646568ms","start":"2026-08-18T23:19:25.22673Z","end":"2026-08-18T23:19:25.342377Z","steps":["trace[1986321799] 'process raft request' (duration: 39.746251ms)","trace[1986321799] 'compare' (duration: 75.788143ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:28.31615Z","caller":"traceutil/trace.go:171","msg":"trace[1116041545] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"377.624246ms","start":"2026-08-18T23:19:27.938498Z","end":"2026-08-18T23:19:28.316122Z","steps":["trace[1116041545] 'process raft request' (duration: 377.469101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:28.316854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:27.938479Z","time spent":"377.753731ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:19:28.761881Z","caller":"traceutil/trace.go:171","msg":"trace[1672445915] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:312; }","duration":"785.159496ms","start":"2026-08-18T23:19:27.976687Z","end":"2026-08-18T23:19:28.761847Z","steps":["trace[1672445915] 'read index received' (duration: 339.299938ms)","trace[1672445915] 'applied index is now lower than readState.Index' (duration: 445.858388ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:28.762099Z","caller":"traceutil/trace.go:171","msg":"trace[212718983] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"820.869456ms","start":"2026-08-18T23:19:27.9412Z","end":"2026-08-18T23:19:28.76207Z","steps":["trace[212718983] 'process raft request' (duration: 756.452923ms)","trace[212718983] 'compare' (duration: 63.995379ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:19:28.76224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:27.941184Z","time spent":"820.985629ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:19:28.762364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.501128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-08-18T23:19:28.762482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.931117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-18T23:19:28.762558Z","caller":"traceutil/trace.go:171","msg":"trace[1613682167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:300; }","duration":"711.026961ms","start":"2026-08-18T23:19:28.051512Z","end":"2026-08-18T23:19:28.762539Z","steps":["trace[1613682167] 'agreement among raft nodes before linearized reading' (duration: 710.71642ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:28.762591Z","caller":"traceutil/trace.go:171","msg":"trace[819077603] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:300; }","duration":"785.790227ms","start":"2026-08-18T23:19:27.976655Z","end":"2026-08-18T23:19:28.762445Z","steps":["trace[819077603] 'agreement among raft nodes before linearized reading' (duration: 785.458596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:28.762641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:28.051502Z","time spent":"711.127454ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-18T23:19:28.762811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:27.976642Z","time spent":"785.988294ms","remote":"127.0.0.1:40600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-18T23:19:44.996889Z","caller":"traceutil/trace.go:171","msg":"trace[677036881] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"117.986103ms","start":"2026-08-18T23:19:44.878874Z","end":"2026-08-18T23:19:44.99686Z","steps":["trace[677036881] 'read index received' (duration: 117.766456ms)","trace[677036881] 'applied index is now lower than readState.Index' (duration: 218.597µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:19:44.997044Z","caller":"traceutil/trace.go:171","msg":"trace[1703838309] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"163.788105ms","start":"2026-08-18T23:19:44.833242Z","end":"2026-08-18T23:19:44.99703Z","steps":["trace[1703838309] 'process raft request' (duration: 163.476886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:44.99726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.370574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-18T23:19:44.997323Z","caller":"traceutil/trace.go:171","msg":"trace[599386379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"118.488938ms","start":"2026-08-18T23:19:44.878819Z","end":"2026-08-18T23:19:44.997308Z","steps":["trace[599386379] 'agreement among raft nodes before linearized reading' (duration: 118.300201ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:45.481385Z","caller":"traceutil/trace.go:171","msg":"trace[1117899583] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"480.667983ms","start":"2026-08-18T23:19:45.00068Z","end":"2026-08-18T23:19:45.481348Z","steps":["trace[1117899583] 'process raft request' (duration: 472.765234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:45.48153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:45.000659Z","time spent":"480.796406ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:19:45.490069Z","caller":"traceutil/trace.go:171","msg":"trace[1127138936] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"489.047617ms","start":"2026-08-18T23:19:45.000998Z","end":"2026-08-18T23:19:45.490046Z","steps":["trace[1127138936] 'process raft request' (duration: 488.938144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:45.490233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:45.000974Z","time spent":"489.1775ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:19:47.500616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501018,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-18T23:19:47.537102Z","caller":"traceutil/trace.go:171","msg":"trace[970993400] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"536.656141ms","start":"2026-08-18T23:19:47.000417Z","end":"2026-08-18T23:19:47.537073Z","steps":["trace[970993400] 'read index received' (duration: 514.776174ms)","trace[970993400] 'applied index is now lower than readState.Index' (duration: 21.876567ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:19:47.537259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.831717ms","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-08-18T23:19:47.537317Z","caller":"traceutil/trace.go:171","msg":"trace[600074888] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"536.934419ms","start":"2026-08-18T23:19:47.000368Z","end":"2026-08-18T23:19:47.537303Z","steps":["trace[600074888] 'agreement among raft nodes before linearized reading' (duration: 536.801435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:47.537349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:47.00035Z","time spent":"536.994051ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-18T23:19:50.581574Z","caller":"traceutil/trace.go:171","msg":"trace[604421639] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"175.889537ms","start":"2026-08-18T23:19:50.40566Z","end":"2026-08-18T23:19:50.58155Z","steps":["trace[604421639] 'process raft request' (duration: 175.668331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.879491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.710143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:19:56.879792Z","caller":"traceutil/trace.go:171","msg":"trace[2047889703] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.231081913s","start":"2026-08-18T23:19:55.648692Z","end":"2026-08-18T23:19:56.879774Z","steps":["trace[2047889703] 'process raft request' (duration: 1.231014511s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.879857Z","caller":"traceutil/trace.go:171","msg":"trace[1591202630] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.236309035s","start":"2026-08-18T23:19:55.643522Z","end":"2026-08-18T23:19:56.879831Z","steps":["trace[1591202630] 'process raft request' (duration: 311.053377ms)","trace[1591202630] 'compare' (duration: 924.533079ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:19:56.879882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:55.648671Z","time spent":"1.231166146s","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:19:56.879957Z","caller":"traceutil/trace.go:171","msg":"trace[395091219] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.236340975s","start":"2026-08-18T23:19:55.643553Z","end":"2026-08-18T23:19:56.879894Z","steps":["trace[395091219] 'process raft request' (duration: 1.236060608s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.880137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:55.643535Z","time spent":"1.23651926s","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:19:56.880217Z","caller":"traceutil/trace.go:171","msg":"trace[1329796571] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.127336213s","start":"2026-08-18T23:19:55.752877Z","end":"2026-08-18T23:19:56.880213Z","steps":["trace[1329796571] 'process raft request' (duration: 1.127251491s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.880205Z","caller":"traceutil/trace.go:171","msg":"trace[304251484] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:538; }","duration":"1.101448994s","start":"2026-08-18T23:19:55.778738Z","end":"2026-08-18T23:19:56.880186Z","steps":["trace[304251484] 'read index received' (duration: 175.92048ms)","trace[304251484] 'applied index is now lower than readState.Index' (duration: 925.527644ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:19:56.879988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:55.643501Z","time spent":"1.236422877s","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T23:19:56.880248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:55.75285Z","time spent":"1.127384413s","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-18T23:19:56.880196Z","caller":"traceutil/trace.go:171","msg":"trace[1580202747] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.222244725s","start":"2026-08-18T23:19:55.657947Z","end":"2026-08-18T23:19:56.880191Z","steps":["trace[1580202747] 'process raft request' (duration: 1.222109742s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:56.880163Z","caller":"traceutil/trace.go:171","msg":"trace[1817585903] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.222259535s","start":"2026-08-18T23:19:55.657897Z","end":"2026-08-18T23:19:56.880156Z","steps":["trace[1817585903] 'process raft request' (duration: 1.222081041s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.880357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:55.657882Z","time spent":"1.2224487s","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:19:56.880366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101631049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:19:56.880398Z","caller":"traceutil/trace.go:171","msg":"trace[2056308608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.101723501s","start":"2026-08-18T23:19:55.778663Z","end":"2026-08-18T23:19:56.880387Z","steps":["trace[2056308608] 'agreement among raft nodes before linearized reading' (duration: 1.101608778s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:56.880439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:55.778642Z","time spent":"1.101789873s","remote":"127.0.0.1:40484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-18T23:19:56.880349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:19:55.657923Z","time spent":"1.222366598s","remote":"127.0.0.1:40544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:19:56.895353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.680768ms","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-08-18T23:19:56.895416Z","caller":"traceutil/trace.go:171","msg":"trace[1314711043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"294.792601ms","start":"2026-08-18T23:19:56.600609Z","end":"2026-08-18T23:19:56.895402Z","steps":["trace[1314711043] 'agreement among raft nodes before linearized reading' (duration: 294.625856ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:57.906593Z","caller":"traceutil/trace.go:171","msg":"trace[321574000] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"179.742973ms","start":"2026-08-18T23:19:57.726822Z","end":"2026-08-18T23:19:57.906565Z","steps":["trace[321574000] 'process raft request' (duration: 178.397836ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:57.99184Z","caller":"traceutil/trace.go:171","msg":"trace[1018258592] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"184.232483ms","start":"2026-08-18T23:19:57.807583Z","end":"2026-08-18T23:19:57.991816Z","steps":["trace[1018258592] 'process raft request' (duration: 184.099299ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:58.440396Z","caller":"traceutil/trace.go:171","msg":"trace[1798320203] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"177.663344ms","start":"2026-08-18T23:19:58.262715Z","end":"2026-08-18T23:19:58.440379Z","steps":["trace[1798320203] 'read index received' (duration: 177.367416ms)","trace[1798320203] 'applied index is now lower than readState.Index' (duration: 295.088µs)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:19:58.440527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.796337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-08-18T23:19:58.440497Z","caller":"traceutil/trace.go:171","msg":"trace[927431478] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"199.43791ms","start":"2026-08-18T23:19:58.241024Z","end":"2026-08-18T23:19:58.440462Z","steps":["trace[927431478] 'process raft request' (duration: 199.171773ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:19:58.440549Z","caller":"traceutil/trace.go:171","msg":"trace[2088498759] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:569; }","duration":"177.854109ms","start":"2026-08-18T23:19:58.262688Z","end":"2026-08-18T23:19:58.440543Z","steps":["trace[2088498759] 'agreement among raft nodes before linearized reading' (duration: 177.757937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:19:58.758163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.686753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-08-18T23:19:58.758257Z","caller":"traceutil/trace.go:171","msg":"trace[788792755] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:569; }","duration":"284.831206ms","start":"2026-08-18T23:19:58.473408Z","end":"2026-08-18T23:19:58.758239Z","steps":["trace[788792755] 'range keys from in-memory index tree' (duration: 284.464397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:04.158481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501379,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:04.351969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100777849s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:04.352065Z","caller":"traceutil/trace.go:171","msg":"trace[969316621] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.374559203s","start":"2026-08-18T23:20:02.977488Z","end":"2026-08-18T23:20:04.352047Z","steps":["trace[969316621] 'process raft request' (duration: 273.621709ms)","trace[969316621] 'compare' (duration: 1.100663806s)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:04.352124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:02.977464Z","time spent":"1.374631005s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:20:04.549898Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.296685877s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-18T23:20:05.802123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000395912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-18T23:20:05.802224Z","caller":"traceutil/trace.go:171","msg":"trace[574606239] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000524654s","start":"2026-08-18T23:20:03.801677Z","end":"2026-08-18T23:20:05.802202Z","steps":["trace[574606239] 'agreement among raft nodes before linearized reading' (duration: 2.000410022s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:05.802273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:03.801665Z","time spent":"2.000596697s","remote":"127.0.0.1:40494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/18 23:20:05 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-18T23:20:06.043895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493404765s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:06.043997Z","caller":"traceutil/trace.go:171","msg":"trace[2068293931] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"2.385806461s","start":"2026-08-18T23:20:03.658175Z","end":"2026-08-18T23:20:06.043981Z","steps":["trace[2068293931] 'read index received' (duration: 891.919034ms)","trace[2068293931] 'applied index is now lower than readState.Index' (duration: 1.493885617s)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:06.044061Z","caller":"traceutil/trace.go:171","msg":"trace[1906238872] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"3.035083196s","start":"2026-08-18T23:20:03.008968Z","end":"2026-08-18T23:20:06.044051Z","steps":["trace[1906238872] 'process raft request' (duration: 1.541437324s)","trace[1906238872] 'compare' (duration: 1.492285207s)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:06.044117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:03.008946Z","time spent":"3.035136997s","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:20:06.044518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.386294304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-18T23:20:06.044571Z","caller":"traceutil/trace.go:171","msg":"trace[2084668153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"2.386389156s","start":"2026-08-18T23:20:03.658167Z","end":"2026-08-18T23:20:06.044556Z","steps":["trace[2084668153] 'agreement among raft nodes before linearized reading' (duration: 2.386162731s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:06.044618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:03.658109Z","time spent":"2.386497339s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-18T23:20:06.0523Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.107425265s","expected-duration":"1s"} {"level":"info","ts":"2026-08-18T23:20:06.0531Z","caller":"traceutil/trace.go:171","msg":"trace[2091882152] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.290386264s","start":"2026-08-18T23:20:04.762672Z","end":"2026-08-18T23:20:06.053059Z","steps":["trace[2091882152] 'process raft request' (duration: 1.290264431s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:06.053221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:04.762622Z","time spent":"1.290551699s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:20:06.108594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.450328086s","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-08-18T23:20:06.10867Z","caller":"traceutil/trace.go:171","msg":"trace[538283273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"2.45047775s","start":"2026-08-18T23:20:03.658176Z","end":"2026-08-18T23:20:06.108654Z","steps":["trace[538283273] 'agreement among raft nodes before linearized reading' (duration: 2.450248403s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.108671Z","caller":"traceutil/trace.go:171","msg":"trace[223531216] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"299.354862ms","start":"2026-08-18T23:20:05.809296Z","end":"2026-08-18T23:20:06.108651Z","steps":["trace[223531216] 'process raft request' (duration: 298.93118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:06.108712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:03.658153Z","time spent":"2.450550291s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-18T23:20:06.109063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.450219053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-18T23:20:06.109132Z","caller":"traceutil/trace.go:171","msg":"trace[624225207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"2.450313775s","start":"2026-08-18T23:20:03.658799Z","end":"2026-08-18T23:20:06.109113Z","steps":["trace[624225207] 'agreement among raft nodes before linearized reading' (duration: 2.45011291s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:06.109178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:03.658791Z","time spent":"2.450378847s","remote":"127.0.0.1:40762","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-08-18T23:20:06.109363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.705091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:06.109462Z","caller":"traceutil/trace.go:171","msg":"trace[1081733608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"299.888895ms","start":"2026-08-18T23:20:05.809551Z","end":"2026-08-18T23:20:06.10944Z","steps":["trace[1081733608] 'agreement among raft nodes before linearized reading' (duration: 299.67501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:06.246506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.564831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:06.246698Z","caller":"traceutil/trace.go:171","msg":"trace[1518752396] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"123.09396ms","start":"2026-08-18T23:20:06.123584Z","end":"2026-08-18T23:20:06.246678Z","steps":["trace[1518752396] 'read index received' (duration: 19.251462ms)","trace[1518752396] 'applied index is now lower than readState.Index' (duration: 103.841378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:06.246774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.184613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:06.246807Z","caller":"traceutil/trace.go:171","msg":"trace[1417206824] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:603; }","duration":"123.252794ms","start":"2026-08-18T23:20:06.123545Z","end":"2026-08-18T23:20:06.246798Z","steps":["trace[1417206824] 'agreement among raft nodes before linearized reading' (duration: 123.197903ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:06.246992Z","caller":"traceutil/trace.go:171","msg":"trace[933014884] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"134.899585ms","start":"2026-08-18T23:20:06.112061Z","end":"2026-08-18T23:20:06.246961Z","steps":["trace[933014884] 'process raft request' (duration: 30.794511ms)","trace[933014884] 'compare' (duration: 103.282124ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:06.247044Z","caller":"traceutil/trace.go:171","msg":"trace[30532793] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"133.809899ms","start":"2026-08-18T23:20:06.113197Z","end":"2026-08-18T23:20:06.247007Z","steps":["trace[30532793] 'process raft request' (duration: 133.396578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:08.281055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:08.781478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:09.28197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:09.781209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00037891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-18T23:20:09.781288Z","caller":"traceutil/trace.go:171","msg":"trace[1350499593] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000502062s","start":"2026-08-18T23:20:07.780766Z","end":"2026-08-18T23:20:09.781268Z","steps":["trace[1350499593] 'agreement among raft nodes before linearized reading' (duration: 2.0004106s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:09.781332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:07.780747Z","time spent":"2.000576425s","remote":"127.0.0.1:40484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/18 23:20:09 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-18T23:20:09.782339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:10.282732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:10.694968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.337993787s","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-08-18T23:20:10.695028Z","caller":"traceutil/trace.go:171","msg":"trace[243904432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"4.33808271s","start":"2026-08-18T23:20:06.35693Z","end":"2026-08-18T23:20:10.695013Z","steps":["trace[243904432] 'range keys from in-memory index tree' (duration: 4.337797183s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.695061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:06.356918Z","time spent":"4.338135971s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-18T23:20:10.705269Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.243681049s","expected-duration":"1s"} {"level":"info","ts":"2026-08-18T23:20:10.775936Z","caller":"traceutil/trace.go:171","msg":"trace[930233866] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"2.995107901s","start":"2026-08-18T23:20:07.780807Z","end":"2026-08-18T23:20:10.775915Z","steps":["trace[930233866] 'read index received' (duration: 2.924702914s)","trace[930233866] 'applied index is now lower than readState.Index' (duration: 70.403997ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:10.77604Z","caller":"traceutil/trace.go:171","msg":"trace[986962133] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"4.145774666s","start":"2026-08-18T23:20:06.630208Z","end":"2026-08-18T23:20:10.775983Z","steps":["trace[986962133] 'process raft request' (duration: 4.144986216s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.776727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:06.630154Z","time spent":"4.146460153s","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:20:10.776749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.663979966s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-18T23:20:10.777358Z","caller":"traceutil/trace.go:171","msg":"trace[1372366910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"2.664104119s","start":"2026-08-18T23:20:08.112734Z","end":"2026-08-18T23:20:10.776838Z","steps":["trace[1372366910] 'agreement among raft nodes before linearized reading' (duration: 2.663873903s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.777533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:08.112666Z","time spent":"2.664752555s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-18T23:20:10.777532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.526147561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-18T23:20:10.777612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.525882714s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-18T23:20:10.777631Z","caller":"traceutil/trace.go:171","msg":"trace[53244206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"2.526244884s","start":"2026-08-18T23:20:08.251339Z","end":"2026-08-18T23:20:10.777584Z","steps":["trace[53244206] 'agreement among raft nodes before linearized reading' (duration: 2.526018428s)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:10.777689Z","caller":"traceutil/trace.go:171","msg":"trace[754765377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"2.525962626s","start":"2026-08-18T23:20:08.251697Z","end":"2026-08-18T23:20:10.777659Z","steps":["trace[754765377] 'agreement among raft nodes before linearized reading' (duration: 2.525789642s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.777684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:08.251324Z","time spent":"2.526347696s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-18T23:20:10.777731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:08.251689Z","time spent":"2.526031977s","remote":"127.0.0.1:40762","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-08-18T23:20:10.778052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.585186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:10.778209Z","caller":"traceutil/trace.go:171","msg":"trace[1520087901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"996.744239ms","start":"2026-08-18T23:20:09.78144Z","end":"2026-08-18T23:20:10.778184Z","steps":["trace[1520087901] 'agreement among raft nodes before linearized reading' (duration: 995.476039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:10.778343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:09.781392Z","time spent":"996.923585ms","remote":"127.0.0.1:40494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-18T23:20:11.315943Z","caller":"traceutil/trace.go:171","msg":"trace[1621021766] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"113.874558ms","start":"2026-08-18T23:20:11.202044Z","end":"2026-08-18T23:20:11.315919Z","steps":["trace[1621021766] 'process raft request' (duration: 113.719465ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:11.571647Z","caller":"traceutil/trace.go:171","msg":"trace[731663421] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"131.025165ms","start":"2026-08-18T23:20:11.440599Z","end":"2026-08-18T23:20:11.571624Z","steps":["trace[731663421] 'read index received' (duration: 130.092472ms)","trace[731663421] 'applied index is now lower than readState.Index' (duration: 931.673µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:11.571746Z","caller":"traceutil/trace.go:171","msg":"trace[1382225785] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"312.972064ms","start":"2026-08-18T23:20:11.25872Z","end":"2026-08-18T23:20:11.571692Z","steps":["trace[1382225785] 'process raft request' (duration: 312.259106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:11.571824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.212259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-18T23:20:11.571877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:11.25869Z","time spent":"313.118148ms","remote":"127.0.0.1:40580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:11.571862Z","caller":"traceutil/trace.go:171","msg":"trace[1956962811] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:614; }","duration":"131.306402ms","start":"2026-08-18T23:20:11.440544Z","end":"2026-08-18T23:20:11.571851Z","steps":["trace[1956962811] 'agreement among raft nodes before linearized reading' (duration: 131.165368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:11.801925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.596826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:11.802075Z","caller":"traceutil/trace.go:171","msg":"trace[914547996] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"222.652666ms","start":"2026-08-18T23:20:11.579402Z","end":"2026-08-18T23:20:11.802055Z","steps":["trace[914547996] 'process raft request' (duration: 74.834264ms)","trace[914547996] 'compare' (duration: 147.463773ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:11.802126Z","caller":"traceutil/trace.go:171","msg":"trace[1482275605] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"175.37103ms","start":"2026-08-18T23:20:11.626738Z","end":"2026-08-18T23:20:11.802109Z","steps":["trace[1482275605] 'process raft request' (duration: 175.297248ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:12.966462Z","caller":"traceutil/trace.go:171","msg":"trace[1784195388] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"133.054834ms","start":"2026-08-18T23:20:12.833374Z","end":"2026-08-18T23:20:12.966429Z","steps":["trace[1784195388] 'process raft request' (duration: 87.052956ms)","trace[1784195388] 'compare' (duration: 45.863885ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:13.238494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.4978ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:13.238696Z","caller":"traceutil/trace.go:171","msg":"trace[1707569960] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"404.875025ms","start":"2026-08-18T23:20:12.833792Z","end":"2026-08-18T23:20:13.238667Z","steps":["trace[1707569960] 'process raft request' (duration: 303.108438ms)","trace[1707569960] 'compare' (duration: 101.389778ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:13.238811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:12.833772Z","time spent":"404.973938ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:13.238919Z","caller":"traceutil/trace.go:171","msg":"trace[926855178] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"404.804043ms","start":"2026-08-18T23:20:12.834045Z","end":"2026-08-18T23:20:13.238849Z","steps":["trace[926855178] 'process raft request' (duration: 404.554217ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:13.239033Z","caller":"traceutil/trace.go:171","msg":"trace[974680027] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"300.555933ms","start":"2026-08-18T23:20:12.938456Z","end":"2026-08-18T23:20:13.239011Z","steps":["trace[974680027] 'read index received' (duration: 198.52988ms)","trace[974680027] 'applied index is now lower than readState.Index' (duration: 102.024103ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:13.239016Z","caller":"traceutil/trace.go:171","msg":"trace[1971093404] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"299.397215ms","start":"2026-08-18T23:20:12.939601Z","end":"2026-08-18T23:20:13.238998Z","steps":["trace[1971093404] 'process raft request' (duration: 299.21373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:13.239132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:12.834034Z","time spent":"405.003208ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:20:13.239241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.774268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k2n5q\" ","response":"range_response_count:1 size:17431"} {"level":"info","ts":"2026-08-18T23:20:13.2393Z","caller":"traceutil/trace.go:171","msg":"trace[1635233016] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k2n5q; range_end:; response_count:1; response_revision:629; }","duration":"300.87672ms","start":"2026-08-18T23:20:12.938407Z","end":"2026-08-18T23:20:13.239283Z","steps":["trace[1635233016] 'agreement among raft nodes before linearized reading' (duration: 300.673195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:13.239349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:12.938383Z","time spent":"300.953972ms","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17454,"request content":"key:\"/registry/pods/kube-system/cilium-k2n5q\" "} {"level":"info","ts":"2026-08-18T23:20:13.527401Z","caller":"traceutil/trace.go:171","msg":"trace[10094230] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"274.908349ms","start":"2026-08-18T23:20:13.252468Z","end":"2026-08-18T23:20:13.527376Z","steps":["trace[10094230] 'process raft request' (duration: 274.755825ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:15.03636Z","caller":"traceutil/trace.go:171","msg":"trace[1720465286] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"255.622686ms","start":"2026-08-18T23:20:14.780712Z","end":"2026-08-18T23:20:15.036334Z","steps":["trace[1720465286] 'read index received' (duration: 255.433611ms)","trace[1720465286] 'applied index is now lower than readState.Index' (duration: 187.905µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:15.03639Z","caller":"traceutil/trace.go:171","msg":"trace[1264267854] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"596.124665ms","start":"2026-08-18T23:20:14.440236Z","end":"2026-08-18T23:20:15.036361Z","steps":["trace[1264267854] 'process raft request' (duration: 595.962911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.036517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:14.440215Z","time spent":"596.242898ms","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-18T23:20:15.036568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.835202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:15.03664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.082135ms","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-08-18T23:20:15.036645Z","caller":"traceutil/trace.go:171","msg":"trace[1602718802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"255.961945ms","start":"2026-08-18T23:20:14.780664Z","end":"2026-08-18T23:20:15.036625Z","steps":["trace[1602718802] 'agreement among raft nodes before linearized reading' (duration: 255.78104ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:15.036679Z","caller":"traceutil/trace.go:171","msg":"trace[623600041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"208.182098ms","start":"2026-08-18T23:20:14.828486Z","end":"2026-08-18T23:20:15.036668Z","steps":["trace[623600041] 'agreement among raft nodes before linearized reading' (duration: 207.979273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.255994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.012607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-18T23:20:15.256154Z","caller":"traceutil/trace.go:171","msg":"trace[1527230749] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"215.612737ms","start":"2026-08-18T23:20:15.040488Z","end":"2026-08-18T23:20:15.256101Z","steps":["trace[1527230749] 'process raft request' (duration: 87.407646ms)","trace[1527230749] 'compare' (duration: 127.858903ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:15.260667Z","caller":"traceutil/trace.go:171","msg":"trace[809913544] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"204.756162ms","start":"2026-08-18T23:20:15.055882Z","end":"2026-08-18T23:20:15.260638Z","steps":["trace[809913544] 'process raft request' (duration: 204.556236ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:15.260777Z","caller":"traceutil/trace.go:171","msg":"trace[720195207] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"219.294165ms","start":"2026-08-18T23:20:15.041438Z","end":"2026-08-18T23:20:15.260732Z","steps":["trace[720195207] 'process raft request' (duration: 218.885445ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:15.581279Z","caller":"traceutil/trace.go:171","msg":"trace[184535089] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"314.119947ms","start":"2026-08-18T23:20:15.26713Z","end":"2026-08-18T23:20:15.58125Z","steps":["trace[184535089] 'process raft request' (duration: 313.956913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:15.581433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:15.267114Z","time spent":"314.236909ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:15.602296Z","caller":"traceutil/trace.go:171","msg":"trace[540525501] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"332.103811ms","start":"2026-08-18T23:20:15.270162Z","end":"2026-08-18T23:20:15.602266Z","steps":["trace[540525501] 'process raft request' (duration: 320.305473ms)","trace[540525501] 'compare' (duration: 11.694896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:20:15.602443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:15.270143Z","time spent":"332.218946ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:20:16.498416Z","caller":"traceutil/trace.go:171","msg":"trace[1367353984] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"108.202892ms","start":"2026-08-18T23:20:16.390072Z","end":"2026-08-18T23:20:16.498274Z","steps":["trace[1367353984] 'process raft request' (duration: 42.603151ms)","trace[1367353984] 'compare' (duration: 65.515639ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:16.778298Z","caller":"traceutil/trace.go:171","msg":"trace[1323707534] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"264.148597ms","start":"2026-08-18T23:20:16.514126Z","end":"2026-08-18T23:20:16.778274Z","steps":["trace[1323707534] 'process raft request' (duration: 263.998564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:17.29322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.139851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-k2n5q\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-08-18T23:20:17.2933Z","caller":"traceutil/trace.go:171","msg":"trace[17309503] range","detail":"{range_begin:/registry/pods/kube-system/cilium-k2n5q; range_end:; response_count:1; response_revision:642; }","duration":"163.242413ms","start":"2026-08-18T23:20:17.13004Z","end":"2026-08-18T23:20:17.293282Z","steps":["trace[17309503] 'range keys from in-memory index tree' (duration: 163.005768ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:17.566645Z","caller":"traceutil/trace.go:171","msg":"trace[906729610] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"259.256047ms","start":"2026-08-18T23:20:17.307363Z","end":"2026-08-18T23:20:17.566619Z","steps":["trace[906729610] 'process raft request' (duration: 259.180826ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:17.566707Z","caller":"traceutil/trace.go:171","msg":"trace[377122499] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"269.183278ms","start":"2026-08-18T23:20:17.297475Z","end":"2026-08-18T23:20:17.566658Z","steps":["trace[377122499] 'process raft request' (duration: 268.887822ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:20.315429Z","caller":"traceutil/trace.go:171","msg":"trace[1825529261] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"133.333761ms","start":"2026-08-18T23:20:20.182064Z","end":"2026-08-18T23:20:20.315398Z","steps":["trace[1825529261] 'process raft request' (duration: 131.709493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:30.711802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:31.212735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874863767501751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-18T23:20:31.32162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.218254407s","expected-duration":"1s"} {"level":"info","ts":"2026-08-18T23:20:31.324413Z","caller":"traceutil/trace.go:171","msg":"trace[75797258] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"1.112706001s","start":"2026-08-18T23:20:30.211685Z","end":"2026-08-18T23:20:31.324391Z","steps":["trace[75797258] 'read index received' (duration: 1.110050964s)","trace[75797258] 'applied index is now lower than readState.Index' (duration: 2.654047ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:20:31.324566Z","caller":"traceutil/trace.go:171","msg":"trace[1550306192] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"1.221255172s","start":"2026-08-18T23:20:30.103267Z","end":"2026-08-18T23:20:31.324523Z","steps":["trace[1550306192] 'process raft request' (duration: 1.218602705s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.32464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112937007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dtsqg\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-08-18T23:20:31.3247Z","caller":"traceutil/trace.go:171","msg":"trace[1635408585] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dtsqg; range_end:; response_count:1; response_revision:782; }","duration":"1.113017979s","start":"2026-08-18T23:20:30.211671Z","end":"2026-08-18T23:20:31.324689Z","steps":["trace[1635408585] 'agreement among raft nodes before linearized reading' (duration: 1.112830435s)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.324752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.211623Z","time spent":"1.1131029s","remote":"127.0.0.1:40688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dtsqg\" "} {"level":"warn","ts":"2026-08-18T23:20:31.324736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.968837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:31.324762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.794839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:31.324815Z","caller":"traceutil/trace.go:171","msg":"trace[2061093963] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:782; }","duration":"900.09499ms","start":"2026-08-18T23:20:30.424702Z","end":"2026-08-18T23:20:31.324797Z","steps":["trace[2061093963] 'agreement among raft nodes before linearized reading' (duration: 899.967957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.324834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.367822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:31.324915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.082051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:31.324921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.40452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:31.324921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.949802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:31.324947Z","caller":"traceutil/trace.go:171","msg":"trace[481622643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"544.141782ms","start":"2026-08-18T23:20:30.780797Z","end":"2026-08-18T23:20:31.324939Z","steps":["trace[481622643] 'agreement among raft nodes before linearized reading' (duration: 544.093431ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:31.324943Z","caller":"traceutil/trace.go:171","msg":"trace[342010000] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:782; }","duration":"938.497105ms","start":"2026-08-18T23:20:30.386404Z","end":"2026-08-18T23:20:31.324901Z","steps":["trace[342010000] 'agreement among raft nodes before linearized reading' (duration: 938.297341ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:31.324967Z","caller":"traceutil/trace.go:171","msg":"trace[1165693781] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:782; }","duration":"971.454981ms","start":"2026-08-18T23:20:30.353495Z","end":"2026-08-18T23:20:31.32495Z","steps":["trace[1165693781] 'agreement among raft nodes before linearized reading' (duration: 971.40456ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:31.324968Z","caller":"traceutil/trace.go:171","msg":"trace[262434459] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:782; }","duration":"926.018494ms","start":"2026-08-18T23:20:30.398937Z","end":"2026-08-18T23:20:31.324956Z","steps":["trace[262434459] 'agreement among raft nodes before linearized reading' (duration: 925.941093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.325018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.274543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:31.325028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.398932Z","time spent":"926.080835ms","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-18T23:20:31.325049Z","caller":"traceutil/trace.go:171","msg":"trace[844408121] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:782; }","duration":"900.337045ms","start":"2026-08-18T23:20:30.424705Z","end":"2026-08-18T23:20:31.325042Z","steps":["trace[844408121] 'agreement among raft nodes before linearized reading' (duration: 900.289444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.325058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.816601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-18T23:20:31.324932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.103251Z","time spent":"1.221425775s","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:20:31.32502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.3864Z","time spent":"938.582987ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-18T23:20:31.325118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.313181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:31.324847Z","caller":"traceutil/trace.go:171","msg":"trace[778923504] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:782; }","duration":"925.949553ms","start":"2026-08-18T23:20:30.398877Z","end":"2026-08-18T23:20:31.324827Z","steps":["trace[778923504] 'agreement among raft nodes before linearized reading' (duration: 925.791239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.325134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.636548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:31.325146Z","caller":"traceutil/trace.go:171","msg":"trace[1095613066] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:782; }","duration":"926.356681ms","start":"2026-08-18T23:20:30.398781Z","end":"2026-08-18T23:20:31.325138Z","steps":["trace[1095613066] 'agreement among raft nodes before linearized reading' (duration: 926.31906ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:31.325169Z","caller":"traceutil/trace.go:171","msg":"trace[585342751] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:782; }","duration":"938.671839ms","start":"2026-08-18T23:20:30.386491Z","end":"2026-08-18T23:20:31.325162Z","steps":["trace[585342751] 'agreement among raft nodes before linearized reading' (duration: 938.625358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.325174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.39877Z","time spent":"926.398092ms","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-08-18T23:20:31.325203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.386457Z","time spent":"938.73838ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-08-18T23:20:31.32521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.398824Z","time spent":"926.355131ms","remote":"127.0.0.1:40376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-08-18T23:20:31.325081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.424684Z","time spent":"900.390136ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-08-18T23:20:31.324811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.390216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:31.325297Z","caller":"traceutil/trace.go:171","msg":"trace[1675680055] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:782; }","duration":"900.920587ms","start":"2026-08-18T23:20:30.424362Z","end":"2026-08-18T23:20:31.325282Z","steps":["trace[1675680055] 'agreement among raft nodes before linearized reading' (duration: 900.350875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.325016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.353489Z","time spent":"971.517613ms","remote":"127.0.0.1:40370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"warn","ts":"2026-08-18T23:20:31.325327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.424346Z","time spent":"900.97176ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-08-18T23:20:31.324866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.424688Z","time spent":"900.167471ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-08-18T23:20:31.324978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.780777Z","time spent":"544.196394ms","remote":"127.0.0.1:40484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-18T23:20:31.325086Z","caller":"traceutil/trace.go:171","msg":"trace[1748079147] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:782; }","duration":"938.855903ms","start":"2026-08-18T23:20:30.386222Z","end":"2026-08-18T23:20:31.325078Z","steps":["trace[1748079147] 'agreement among raft nodes before linearized reading' (duration: 938.815542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.325562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.386213Z","time spent":"939.335353ms","remote":"127.0.0.1:40374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-08-18T23:20:31.325091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.85456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:31.325728Z","caller":"traceutil/trace.go:171","msg":"trace[881453361] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:782; }","duration":"972.497353ms","start":"2026-08-18T23:20:30.353216Z","end":"2026-08-18T23:20:31.325713Z","steps":["trace[881453361] 'agreement among raft nodes before linearized reading' (duration: 971.84811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.325771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.353212Z","time spent":"972.545545ms","remote":"127.0.0.1:40370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-18T23:20:31.326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.426132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-18T23:20:31.326045Z","caller":"traceutil/trace.go:171","msg":"trace[263104558] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:782; }","duration":"972.850201ms","start":"2026-08-18T23:20:30.353185Z","end":"2026-08-18T23:20:31.326035Z","steps":["trace[263104558] 'agreement among raft nodes before linearized reading' (duration: 971.426431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:20:31.326075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:20:30.353174Z","time spent":"972.892202ms","remote":"127.0.0.1:40370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"info","ts":"2026-08-18T23:20:32.363388Z","caller":"traceutil/trace.go:171","msg":"trace[742641280] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"133.944152ms","start":"2026-08-18T23:20:32.229419Z","end":"2026-08-18T23:20:32.363363Z","steps":["trace[742641280] 'process raft request' (duration: 133.783828ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:20:45.655143Z","caller":"traceutil/trace.go:171","msg":"trace[321676818] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"152.136038ms","start":"2026-08-18T23:20:45.502981Z","end":"2026-08-18T23:20:45.655117Z","steps":["trace[321676818] 'process raft request' (duration: 151.644388ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:14.124868Z","caller":"traceutil/trace.go:171","msg":"trace[335240011] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"154.212852ms","start":"2026-08-18T23:21:13.970608Z","end":"2026-08-18T23:21:14.124821Z","steps":["trace[335240011] 'process raft request' (duration: 69.715178ms)","trace[335240011] 'compare' (duration: 84.218049ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:21:30.057193Z","caller":"traceutil/trace.go:171","msg":"trace[86610526] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"107.915447ms","start":"2026-08-18T23:21:29.949153Z","end":"2026-08-18T23:21:30.057068Z","steps":["trace[86610526] 'process raft request' (duration: 107.730112ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:30.208051Z","caller":"traceutil/trace.go:171","msg":"trace[1321009909] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"125.358216ms","start":"2026-08-18T23:21:30.082663Z","end":"2026-08-18T23:21:30.208021Z","steps":["trace[1321009909] 'process raft request' (duration: 115.844401ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:30.211236Z","caller":"traceutil/trace.go:171","msg":"trace[475754877] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"128.448421ms","start":"2026-08-18T23:21:30.082771Z","end":"2026-08-18T23:21:30.21122Z","steps":["trace[475754877] 'process raft request' (duration: 128.344719ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:30.317778Z","caller":"traceutil/trace.go:171","msg":"trace[1106034810] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"101.901731ms","start":"2026-08-18T23:21:30.215855Z","end":"2026-08-18T23:21:30.317757Z","steps":["trace[1106034810] 'process raft request' (duration: 97.500524ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:32.237706Z","caller":"traceutil/trace.go:171","msg":"trace[1404731000] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"156.754549ms","start":"2026-08-18T23:21:32.080928Z","end":"2026-08-18T23:21:32.237682Z","steps":["trace[1404731000] 'read index received' (duration: 156.594477ms)","trace[1404731000] 'applied index is now lower than readState.Index' (duration: 158.952µs)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:21:32.238061Z","caller":"traceutil/trace.go:171","msg":"trace[582041131] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"173.497238ms","start":"2026-08-18T23:21:32.064551Z","end":"2026-08-18T23:21:32.238048Z","steps":["trace[582041131] 'process raft request' (duration: 172.98812ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:21:32.238466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.521752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-18T23:21:32.238536Z","caller":"traceutil/trace.go:171","msg":"trace[1657854506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1117; }","duration":"157.635783ms","start":"2026-08-18T23:21:32.080883Z","end":"2026-08-18T23:21:32.238519Z","steps":["trace[1657854506] 'agreement among raft nodes before linearized reading' (duration: 157.375749ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:32.348465Z","caller":"traceutil/trace.go:171","msg":"trace[449351477] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"105.34982ms","start":"2026-08-18T23:21:32.243085Z","end":"2026-08-18T23:21:32.348435Z","steps":["trace[449351477] 'process raft request' (duration: 105.129046ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:32.348306Z","caller":"traceutil/trace.go:171","msg":"trace[2051987308] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"105.579573ms","start":"2026-08-18T23:21:32.242672Z","end":"2026-08-18T23:21:32.348251Z","steps":["trace[2051987308] 'process raft request' (duration: 105.449011ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:46.553905Z","caller":"traceutil/trace.go:171","msg":"trace[281828372] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"146.136167ms","start":"2026-08-18T23:21:46.407737Z","end":"2026-08-18T23:21:46.553874Z","steps":["trace[281828372] 'process raft request' (duration: 125.129005ms)","trace[281828372] 'compare' (duration: 20.806928ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:21:46.553939Z","caller":"traceutil/trace.go:171","msg":"trace[553552203] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"101.874585ms","start":"2026-08-18T23:21:46.452037Z","end":"2026-08-18T23:21:46.553912Z","steps":["trace[553552203] 'read index received' (duration: 80.843134ms)","trace[553552203] 'applied index is now lower than readState.Index' (duration: 21.029031ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:21:46.554157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.10844ms","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-08-18T23:21:46.554301Z","caller":"traceutil/trace.go:171","msg":"trace[788028985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"102.304643ms","start":"2026-08-18T23:21:46.451976Z","end":"2026-08-18T23:21:46.554281Z","steps":["trace[788028985] 'agreement among raft nodes before linearized reading' (duration: 102.033158ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:21:56.819535Z","caller":"traceutil/trace.go:171","msg":"trace[1346780837] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"134.260717ms","start":"2026-08-18T23:21:56.68524Z","end":"2026-08-18T23:21:56.8195Z","steps":["trace[1346780837] 'process raft request' (duration: 123.376078ms)","trace[1346780837] 'compare' (duration: 10.651435ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:21:56.819657Z","caller":"traceutil/trace.go:171","msg":"trace[1640776893] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"132.904094ms","start":"2026-08-18T23:21:56.686519Z","end":"2026-08-18T23:21:56.819423Z","steps":["trace[1640776893] 'read index received' (duration: 122.173618ms)","trace[1640776893] 'applied index is now lower than readState.Index' (duration: 10.729076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:21:56.819848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.302811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-18T23:21:56.819968Z","caller":"traceutil/trace.go:171","msg":"trace[1310585005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"133.458134ms","start":"2026-08-18T23:21:56.686485Z","end":"2026-08-18T23:21:56.819943Z","steps":["trace[1310585005] 'agreement among raft nodes before linearized reading' (duration: 133.145859ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:03.80305Z","caller":"traceutil/trace.go:171","msg":"trace[881563187] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"159.149315ms","start":"2026-08-18T23:22:03.643878Z","end":"2026-08-18T23:22:03.803027Z","steps":["trace[881563187] 'process raft request' (duration: 159.038164ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:31.371744Z","caller":"traceutil/trace.go:171","msg":"trace[2044524079] linearizableReadLoop","detail":"{readStateIndex:1408; appliedIndex:1407; }","duration":"369.051736ms","start":"2026-08-18T23:22:31.002666Z","end":"2026-08-18T23:22:31.371718Z","steps":["trace[2044524079] 'read index received' (duration: 294.151244ms)","trace[2044524079] 'applied index is now lower than readState.Index' (duration: 74.899502ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:22:31.371819Z","caller":"traceutil/trace.go:171","msg":"trace[1534450155] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"430.702302ms","start":"2026-08-18T23:22:30.941087Z","end":"2026-08-18T23:22:31.37179Z","steps":["trace[1534450155] 'process raft request' (duration: 355.732899ms)","trace[1534450155] 'compare' (duration: 74.78773ms)"],"step_count":2} {"level":"warn","ts":"2026-08-18T23:22:31.37738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:30.941047Z","time spent":"436.225539ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:22:31.377465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.681633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-18T23:22:31.377544Z","caller":"traceutil/trace.go:171","msg":"trace[360353897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1348; }","duration":"303.781205ms","start":"2026-08-18T23:22:31.073724Z","end":"2026-08-18T23:22:31.377505Z","steps":["trace[360353897] 'agreement among raft nodes before linearized reading' (duration: 303.535641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:22:31.377609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:31.073717Z","time spent":"303.861366ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-18T23:22:31.372015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.348111ms","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-08-18T23:22:31.378025Z","caller":"traceutil/trace.go:171","msg":"trace[1442676985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1348; }","duration":"375.409346ms","start":"2026-08-18T23:22:31.002597Z","end":"2026-08-18T23:22:31.378007Z","steps":["trace[1442676985] 'agreement among raft nodes before linearized reading' (duration: 369.29335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:22:31.378198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:31.002577Z","time spent":"375.605799ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-18T23:22:31.37778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.795128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-18T23:22:31.377912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.376321ms","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-08-18T23:22:31.378456Z","caller":"traceutil/trace.go:171","msg":"trace[2118975723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1348; }","duration":"209.432767ms","start":"2026-08-18T23:22:31.16895Z","end":"2026-08-18T23:22:31.378383Z","steps":["trace[2118975723] 'agreement among raft nodes before linearized reading' (duration: 208.757547ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:31.378496Z","caller":"traceutil/trace.go:171","msg":"trace[1929005978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1348; }","duration":"271.001101ms","start":"2026-08-18T23:22:31.10748Z","end":"2026-08-18T23:22:31.378481Z","steps":["trace[1929005978] 'agreement among raft nodes before linearized reading' (duration: 270.28278ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:31.866326Z","caller":"traceutil/trace.go:171","msg":"trace[555852700] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"485.402957ms","start":"2026-08-18T23:22:31.380898Z","end":"2026-08-18T23:22:31.866301Z","steps":["trace[555852700] 'process raft request' (duration: 485.227204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:22:31.866471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:31.380886Z","time spent":"485.513429ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:22:31.909486Z","caller":"traceutil/trace.go:171","msg":"trace[2089053976] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"527.732899ms","start":"2026-08-18T23:22:31.381727Z","end":"2026-08-18T23:22:31.90946Z","steps":["trace[2089053976] 'process raft request' (duration: 527.569506ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:22:31.909797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:31.381719Z","time spent":"527.83942ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-18T23:22:31.909817Z","caller":"traceutil/trace.go:171","msg":"trace[1517054621] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1408; }","duration":"462.477058ms","start":"2026-08-18T23:22:31.447315Z","end":"2026-08-18T23:22:31.909792Z","steps":["trace[1517054621] 'read index received' (duration: 418.850036ms)","trace[1517054621] 'applied index is now lower than readState.Index' (duration: 43.626012ms)"],"step_count":2} {"level":"info","ts":"2026-08-18T23:22:31.909856Z","caller":"traceutil/trace.go:171","msg":"trace[1265883355] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"527.904891ms","start":"2026-08-18T23:22:31.381895Z","end":"2026-08-18T23:22:31.9098Z","steps":["trace[1265883355] 'process raft request' (duration: 527.527255ms)"],"step_count":1} {"level":"info","ts":"2026-08-18T23:22:31.910023Z","caller":"traceutil/trace.go:171","msg":"trace[1722766021] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"528.068263ms","start":"2026-08-18T23:22:31.381941Z","end":"2026-08-18T23:22:31.910009Z","steps":["trace[1722766021] 'process raft request' (duration: 527.751848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:22:31.910036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.708911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-18T23:22:31.91007Z","caller":"traceutil/trace.go:171","msg":"trace[1646916484] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1352; }","duration":"462.793033ms","start":"2026-08-18T23:22:31.447267Z","end":"2026-08-18T23:22:31.91006Z","steps":["trace[1646916484] 'agreement among raft nodes before linearized reading' (duration: 462.61811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-18T23:22:31.910099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:31.381888Z","time spent":"528.105444ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:22:31.91012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:31.381911Z","time spent":"528.148895ms","remote":"127.0.0.1:40762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-18T23:22:31.91011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-18T23:22:31.447251Z","time spent":"462.851574ms","remote":"127.0.0.1:40526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "}