{"level":"warn","ts":"2026-02-17T17:51:23.452047Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T17:51:23.452135Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.166: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.19.213.166:2380","--initial-cluster=instance=https://199.19.213.166:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.166:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.166: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-02-17T17:51:23.452452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-17T17:51:23.452468Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.166:2380"]} {"level":"info","ts":"2026-02-17T17:51:23.452504Z","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-02-17T17:51:23.453334Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"]} {"level":"info","ts":"2026-02-17T17:51:23.45369Z","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":2,"max-cpu-available":2,"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.19.213.166:2380"],"listen-peer-urls":["https://199.19.213.166:2380"],"advertise-client-urls":["https://199.19.213.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.166: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-02-17T17:51:23.459871Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.889599ms"} {"level":"info","ts":"2026-02-17T17:51:23.466171Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a8829716614d67b","cluster-id":"95303c3f9d508ee2"} {"level":"info","ts":"2026-02-17T17:51:23.466275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T17:51:23.466668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became follower at term 0"} {"level":"info","ts":"2026-02-17T17:51:23.466693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a8829716614d67b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T17:51:23.4667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became follower at term 1"} {"level":"info","ts":"2026-02-17T17:51:23.466967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b switched to configuration voters=(7676431131873695355)"} {"level":"warn","ts":"2026-02-17T17:51:23.473475Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T17:51:23.477081Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T17:51:23.48013Z","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-02-17T17:51:23.482278Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a8829716614d67b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T17:51:23.498333Z","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-02-17T17:51:23.498883Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a8829716614d67b","initial-advertise-peer-urls":["https://199.19.213.166:2380"],"listen-peer-urls":["https://199.19.213.166:2380"],"advertise-client-urls":["https://199.19.213.166:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.166:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T17:51:23.49893Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T17:51:23.498504Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.166:2380"} {"level":"info","ts":"2026-02-17T17:51:23.499248Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.166:2380"} {"level":"info","ts":"2026-02-17T17:51:23.500454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b switched to configuration voters=(7676431131873695355)"} {"level":"info","ts":"2026-02-17T17:51:23.500612Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95303c3f9d508ee2","local-member-id":"6a8829716614d67b","added-peer-id":"6a8829716614d67b","added-peer-peer-urls":["https://199.19.213.166:2380"]} {"level":"info","ts":"2026-02-17T17:51:23.500822Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a8829716614d67b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T17:51:23.500866Z","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-02-17T17:51:23.502703Z","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-02-17T17:51:23.502712Z","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-02-17T17:51:23.967712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T17:51:23.967751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T17:51:23.967772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b received MsgPreVoteResp from 6a8829716614d67b at term 1"} {"level":"info","ts":"2026-02-17T17:51:23.967809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became candidate at term 2"} {"level":"info","ts":"2026-02-17T17:51:23.967814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b received MsgVoteResp from 6a8829716614d67b at term 2"} {"level":"info","ts":"2026-02-17T17:51:23.967822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a8829716614d67b became leader at term 2"} {"level":"info","ts":"2026-02-17T17:51:23.967831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a8829716614d67b elected leader 6a8829716614d67b at term 2"} {"level":"info","ts":"2026-02-17T17:51:23.969913Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T17:51:23.97316Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95303c3f9d508ee2","local-member-id":"6a8829716614d67b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T17:51:23.973225Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T17:51:23.973243Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T17:51:23.973259Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a8829716614d67b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.166:2379]}","request-path":"/0/members/6a8829716614d67b/attributes","cluster-id":"95303c3f9d508ee2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T17:51:23.973388Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T17:51:23.973502Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T17:51:23.974127Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T17:51:23.974141Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T17:51:23.978216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T17:51:23.982275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.166:2379"} {"level":"info","ts":"2026-02-17T17:51:56.918353Z","caller":"traceutil/trace.go:171","msg":"trace[1849715209] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"139.076274ms","start":"2026-02-17T17:51:56.779261Z","end":"2026-02-17T17:51:56.918338Z","steps":["trace[1849715209] 'process raft request' (duration: 138.988352ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:51:57.09547Z","caller":"traceutil/trace.go:171","msg":"trace[2032072202] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"173.772695ms","start":"2026-02-17T17:51:56.92168Z","end":"2026-02-17T17:51:57.095453Z","steps":["trace[2032072202] 'process raft request' (duration: 170.044646ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:51:57.099585Z","caller":"traceutil/trace.go:171","msg":"trace[2042552147] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"115.937696ms","start":"2026-02-17T17:51:56.983556Z","end":"2026-02-17T17:51:57.099494Z","steps":["trace[2042552147] 'process raft request' (duration: 115.854724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:51:58.834493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.138836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T17:51:58.834552Z","caller":"traceutil/trace.go:171","msg":"trace[1754640544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"192.234837ms","start":"2026-02-17T17:51:58.642305Z","end":"2026-02-17T17:51:58.83454Z","steps":["trace[1754640544] 'range keys from in-memory index tree' (duration: 192.091185ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:51:58.91445Z","caller":"traceutil/trace.go:171","msg":"trace[399829178] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"218.114786ms","start":"2026-02-17T17:51:58.696324Z","end":"2026-02-17T17:51:58.914439Z","steps":["trace[399829178] 'process raft request' (duration: 217.481465ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:51:58.914006Z","caller":"traceutil/trace.go:171","msg":"trace[814019918] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"135.983766ms","start":"2026-02-17T17:51:58.778007Z","end":"2026-02-17T17:51:58.913991Z","steps":["trace[814019918] 'read index received' (duration: 135.64681ms)","trace[814019918] 'applied index is now lower than readState.Index' (duration: 336.386µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T17:51:58.914714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.699569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-17T17:51:58.914757Z","caller":"traceutil/trace.go:171","msg":"trace[474502992] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:571; }","duration":"136.75729ms","start":"2026-02-17T17:51:58.777968Z","end":"2026-02-17T17:51:58.914726Z","steps":["trace[474502992] 'agreement among raft nodes before linearized reading' (duration: 136.647698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:04.240877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.600088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T17:52:04.240962Z","caller":"traceutil/trace.go:171","msg":"trace[119153277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"670.728482ms","start":"2026-02-17T17:52:03.570215Z","end":"2026-02-17T17:52:04.240944Z","steps":["trace[119153277] 'range keys from in-memory index tree' (duration: 670.467625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:04.240997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:03.570168Z","time spent":"670.820074ms","remote":"127.0.0.1:56470","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-02-17T17:52:04.241258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.823177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-26r4l\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T17:52:04.24129Z","caller":"traceutil/trace.go:171","msg":"trace[1054259683] range","detail":"{range_begin:/registry/pods/kube-system/cilium-26r4l; range_end:; response_count:1; response_revision:590; }","duration":"273.882368ms","start":"2026-02-17T17:52:03.967398Z","end":"2026-02-17T17:52:04.241281Z","steps":["trace[1054259683] 'range keys from in-memory index tree' (duration: 273.678245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:20.543722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.353844ms","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-02-17T17:52:20.543774Z","caller":"traceutil/trace.go:171","msg":"trace[658858926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"102.442555ms","start":"2026-02-17T17:52:20.441319Z","end":"2026-02-17T17:52:20.543762Z","steps":["trace[658858926] 'range keys from in-memory index tree' (duration: 102.227972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.042156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.807454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T17:52:23.042243Z","caller":"traceutil/trace.go:171","msg":"trace[2056144556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"476.912365ms","start":"2026-02-17T17:52:22.565302Z","end":"2026-02-17T17:52:23.042214Z","steps":["trace[2056144556] 'range keys from in-memory index tree' (duration: 476.765213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.042277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:22.565294Z","time spent":"476.975656ms","remote":"127.0.0.1:56468","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-02-17T17:52:23.042452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.971562ms","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-02-17T17:52:23.042495Z","caller":"traceutil/trace.go:171","msg":"trace[1317632617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"493.044553ms","start":"2026-02-17T17:52:22.549439Z","end":"2026-02-17T17:52:23.042483Z","steps":["trace[1317632617] 'range keys from in-memory index tree' (duration: 492.85387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.042517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:22.549419Z","time spent":"493.093754ms","remote":"127.0.0.1:56732","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-02-17T17:52:23.407104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.290117ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T17:52:23.407198Z","caller":"traceutil/trace.go:171","msg":"trace[1565118806] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"735.755714ms","start":"2026-02-17T17:52:22.671426Z","end":"2026-02-17T17:52:23.407182Z","steps":["trace[1565118806] 'read index received' (duration: 75.297973ms)","trace[1565118806] 'applied index is now lower than readState.Index' (duration: 660.456481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T17:52:23.407372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.939358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-26r4l\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-17T17:52:23.407688Z","caller":"traceutil/trace.go:171","msg":"trace[850100354] range","detail":"{range_begin:/registry/pods/kube-system/cilium-26r4l; range_end:; response_count:1; response_revision:679; }","duration":"736.280054ms","start":"2026-02-17T17:52:22.671397Z","end":"2026-02-17T17:52:23.407677Z","steps":["trace[850100354] 'agreement among raft nodes before linearized reading' (duration: 735.837465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.407721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:22.671382Z","time spent":"736.329045ms","remote":"127.0.0.1:56646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-26r4l\" "} {"level":"info","ts":"2026-02-17T17:52:23.478695Z","caller":"traceutil/trace.go:171","msg":"trace[314588319] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"433.807926ms","start":"2026-02-17T17:52:23.04487Z","end":"2026-02-17T17:52:23.478678Z","steps":["trace[314588319] 'process raft request' (duration: 433.667663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.479102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:23.044861Z","time spent":"433.882009ms","remote":"127.0.0.1:56732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T17:52:23.479451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.111439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T17:52:23.479475Z","caller":"traceutil/trace.go:171","msg":"trace[1545006540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"436.15519ms","start":"2026-02-17T17:52:23.043313Z","end":"2026-02-17T17:52:23.479468Z","steps":["trace[1545006540] 'agreement among raft nodes before linearized reading' (duration: 436.119289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.479492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:23.043303Z","time spent":"436.18601ms","remote":"127.0.0.1:56470","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-02-17T17:52:23.979586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15455118537050247374,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T17:52:23.99093Z","caller":"traceutil/trace.go:171","msg":"trace[1420594357] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"511.498834ms","start":"2026-02-17T17:52:23.479415Z","end":"2026-02-17T17:52:23.990914Z","steps":["trace[1420594357] 'read index received' (duration: 511.343622ms)","trace[1420594357] 'applied index is now lower than readState.Index' (duration: 154.612µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T17:52:23.991001Z","caller":"traceutil/trace.go:171","msg":"trace[330291328] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"562.430197ms","start":"2026-02-17T17:52:23.428565Z","end":"2026-02-17T17:52:23.990996Z","steps":["trace[330291328] 'process raft request' (duration: 562.239073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.991071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:23.42855Z","time spent":"562.466937ms","remote":"127.0.0.1:56646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T17:52:23.991201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.563942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-17T17:52:23.991216Z","caller":"traceutil/trace.go:171","msg":"trace[1044250349] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"527.603913ms","start":"2026-02-17T17:52:23.463607Z","end":"2026-02-17T17:52:23.991211Z","steps":["trace[1044250349] 'agreement among raft nodes before linearized reading' (duration: 527.54055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.99123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:23.463592Z","time spent":"527.635154ms","remote":"127.0.0.1:56732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T17:52:23.991369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.245224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T17:52:23.991407Z","caller":"traceutil/trace.go:171","msg":"trace[604117336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"421.331826ms","start":"2026-02-17T17:52:23.570061Z","end":"2026-02-17T17:52:23.991393Z","steps":["trace[604117336] 'agreement among raft nodes before linearized reading' (duration: 421.270655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:23.991432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:23.570045Z","time spent":"421.382067ms","remote":"127.0.0.1:56468","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-02-17T17:52:24.816826Z","caller":"traceutil/trace.go:171","msg":"trace[401471860] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"105.134735ms","start":"2026-02-17T17:52:24.711673Z","end":"2026-02-17T17:52:24.816808Z","steps":["trace[401471860] 'process raft request' (duration: 103.337002ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:52:24.81549Z","caller":"traceutil/trace.go:171","msg":"trace[607359721] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"104.130076ms","start":"2026-02-17T17:52:24.711338Z","end":"2026-02-17T17:52:24.815468Z","steps":["trace[607359721] 'process raft request' (duration: 70.021195ms)","trace[607359721] 'compare' (duration: 32.645964ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T17:52:40.664945Z","caller":"traceutil/trace.go:171","msg":"trace[1231301758] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"227.964062ms","start":"2026-02-17T17:52:40.436957Z","end":"2026-02-17T17:52:40.664921Z","steps":["trace[1231301758] 'read index received' (duration: 227.713228ms)","trace[1231301758] 'applied index is now lower than readState.Index' (duration: 249.884µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T17:52:40.665174Z","caller":"traceutil/trace.go:171","msg":"trace[1717747665] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"246.452612ms","start":"2026-02-17T17:52:40.418706Z","end":"2026-02-17T17:52:40.665159Z","steps":["trace[1717747665] 'process raft request' (duration: 246.049686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:40.665708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.742036ms","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-02-17T17:52:40.665931Z","caller":"traceutil/trace.go:171","msg":"trace[408672282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"228.98118ms","start":"2026-02-17T17:52:40.436935Z","end":"2026-02-17T17:52:40.665916Z","steps":["trace[408672282] 'agreement among raft nodes before linearized reading' (duration: 228.631714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:40.871114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.334308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T17:52:40.871199Z","caller":"traceutil/trace.go:171","msg":"trace[1361018216] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"206.152925ms","start":"2026-02-17T17:52:40.665031Z","end":"2026-02-17T17:52:40.871184Z","steps":["trace[1361018216] 'read index received' (duration: 33.688764ms)","trace[1361018216] 'applied index is now lower than readState.Index' (duration: 172.463101ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T17:52:40.871267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.2252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T17:52:40.871292Z","caller":"traceutil/trace.go:171","msg":"trace[1639979488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"302.273621ms","start":"2026-02-17T17:52:40.569008Z","end":"2026-02-17T17:52:40.871282Z","steps":["trace[1639979488] 'agreement among raft nodes before linearized reading' (duration: 302.21629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:52:40.87132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T17:52:40.568995Z","time spent":"302.318361ms","remote":"127.0.0.1:56468","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-02-17T17:52:40.871557Z","caller":"traceutil/trace.go:171","msg":"trace[740270719] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"279.333323ms","start":"2026-02-17T17:52:40.59221Z","end":"2026-02-17T17:52:40.871544Z","steps":["trace[740270719] 'process raft request' (duration: 106.502066ms)","trace[740270719] 'compare' (duration: 172.187305ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T17:52:40.878496Z","caller":"traceutil/trace.go:171","msg":"trace[1228395615] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"208.438194ms","start":"2026-02-17T17:52:40.670044Z","end":"2026-02-17T17:52:40.878483Z","steps":["trace[1228395615] 'process raft request' (duration: 208.218391ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:52:52.352552Z","caller":"traceutil/trace.go:171","msg":"trace[1621531256] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"116.250426ms","start":"2026-02-17T17:52:52.236286Z","end":"2026-02-17T17:52:52.352537Z","steps":["trace[1621531256] 'process raft request' (duration: 116.150094ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:52:53.050241Z","caller":"traceutil/trace.go:171","msg":"trace[557590396] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"121.829082ms","start":"2026-02-17T17:52:52.928397Z","end":"2026-02-17T17:52:53.050226Z","steps":["trace[557590396] 'process raft request' (duration: 121.738461ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:53:05.83477Z","caller":"traceutil/trace.go:171","msg":"trace[1864612270] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"163.368973ms","start":"2026-02-17T17:53:05.671384Z","end":"2026-02-17T17:53:05.834753Z","steps":["trace[1864612270] 'process raft request' (duration: 163.271901ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:53:11.339463Z","caller":"traceutil/trace.go:171","msg":"trace[1016232704] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"216.791689ms","start":"2026-02-17T17:53:11.122652Z","end":"2026-02-17T17:53:11.339443Z","steps":["trace[1016232704] 'process raft request' (duration: 216.674056ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:53:11.53287Z","caller":"traceutil/trace.go:171","msg":"trace[1855911377] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"213.420411ms","start":"2026-02-17T17:53:11.319431Z","end":"2026-02-17T17:53:11.532852Z","steps":["trace[1855911377] 'process raft request' (duration: 213.314479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T17:53:11.683596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.370869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T17:53:11.683613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.513247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T17:53:11.683701Z","caller":"traceutil/trace.go:171","msg":"trace[404645199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"117.633109ms","start":"2026-02-17T17:53:11.566046Z","end":"2026-02-17T17:53:11.683679Z","steps":["trace[404645199] 'range keys from in-memory index tree' (duration: 117.428926ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:53:11.68365Z","caller":"traceutil/trace.go:171","msg":"trace[1438410752] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:925; }","duration":"100.465431ms","start":"2026-02-17T17:53:11.583173Z","end":"2026-02-17T17:53:11.683638Z","steps":["trace[1438410752] 'count revisions from in-memory index tree' (duration: 100.258318ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:53:50.096009Z","caller":"traceutil/trace.go:171","msg":"trace[912575769] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"167.811435ms","start":"2026-02-17T17:53:49.928169Z","end":"2026-02-17T17:53:50.09598Z","steps":["trace[912575769] 'process raft request' (duration: 167.705123ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T17:56:34.923628Z","caller":"traceutil/trace.go:171","msg":"trace[458247462] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"110.123085ms","start":"2026-02-17T17:56:34.813482Z","end":"2026-02-17T17:56:34.923605Z","steps":["trace[458247462] 'process raft request' (duration: 109.971543ms)"],"step_count":1}