{"level":"warn","ts":"2026-05-20T12:58:31.753688Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T12:58:31.753805Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.205: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.205:2380","--initial-cluster=instance=https://199.19.213.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-20T12:58:31.753904Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T12:58:31.753911Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.205:2380"]} {"level":"info","ts":"2026-05-20T12:58:31.753934Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T12:58:31.754611Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"]} {"level":"info","ts":"2026-05-20T12:58:31.754709Z","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.19.213.205:2380"],"listen-peer-urls":["https://199.19.213.205:2380"],"advertise-client-urls":["https://199.19.213.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-20T12:58:31.758368Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.429136ms"} {"level":"info","ts":"2026-05-20T12:58:31.764057Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f69f598a0f963672","cluster-id":"740466542a91995c"} {"level":"info","ts":"2026-05-20T12:58:31.764205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T12:58:31.764235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became follower at term 0"} {"level":"info","ts":"2026-05-20T12:58:31.764254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f69f598a0f963672 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T12:58:31.764268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became follower at term 1"} {"level":"info","ts":"2026-05-20T12:58:31.764322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=(17771021104129128050)"} {"level":"warn","ts":"2026-05-20T12:58:31.771592Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T12:58:31.773724Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T12:58:31.77475Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-20T12:58:31.777026Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f69f598a0f963672","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T12:58:31.777829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f69f598a0f963672","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T12:58:31.778049Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T12:58:31.77816Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T12:58:31.778224Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T12:58:31.780087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=(17771021104129128050)"} {"level":"info","ts":"2026-05-20T12:58:31.780381Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"740466542a91995c","local-member-id":"f69f598a0f963672","added-peer-id":"f69f598a0f963672","added-peer-peer-urls":["https://199.19.213.205:2380"]} {"level":"info","ts":"2026-05-20T12:58:31.782791Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T12:58:31.783093Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.205:2380"} {"level":"info","ts":"2026-05-20T12:58:31.783171Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.205:2380"} {"level":"info","ts":"2026-05-20T12:58:31.784717Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T12:58:31.78466Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f69f598a0f963672","initial-advertise-peer-urls":["https://199.19.213.205:2380"],"listen-peer-urls":["https://199.19.213.205:2380"],"advertise-client-urls":["https://199.19.213.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T12:58:32.165023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T12:58:32.165174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T12:58:32.165232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 received MsgPreVoteResp from f69f598a0f963672 at term 1"} {"level":"info","ts":"2026-05-20T12:58:32.165252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became candidate at term 2"} {"level":"info","ts":"2026-05-20T12:58:32.165262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 received MsgVoteResp from f69f598a0f963672 at term 2"} {"level":"info","ts":"2026-05-20T12:58:32.165276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became leader at term 2"} {"level":"info","ts":"2026-05-20T12:58:32.165311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f69f598a0f963672 elected leader f69f598a0f963672 at term 2"} {"level":"info","ts":"2026-05-20T12:58:32.165926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:32.167495Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f69f598a0f963672","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.205:2379]}","request-path":"/0/members/f69f598a0f963672/attributes","cluster-id":"740466542a91995c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T12:58:32.167543Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T12:58:32.167986Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"740466542a91995c","local-member-id":"f69f598a0f963672","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:32.168143Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T12:58:32.168101Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:32.168214Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:32.168365Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T12:58:32.168387Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T12:58:32.170992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.205:2379"} {"level":"info","ts":"2026-05-20T12:58:32.172132Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T12:58:41.833703Z","caller":"traceutil/trace.go:171","msg":"trace[55457768] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"116.422982ms","start":"2026-05-20T12:58:41.717214Z","end":"2026-05-20T12:58:41.833637Z","steps":["trace[55457768] 'process raft request' (duration: 116.232828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:42.184887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.339996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T12:58:42.18513Z","caller":"traceutil/trace.go:171","msg":"trace[1721704455] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:359; }","duration":"130.742477ms","start":"2026-05-20T12:58:42.054372Z","end":"2026-05-20T12:58:42.185114Z","steps":["trace[1721704455] 'read index received' (duration: 123.086349ms)","trace[1721704455] 'applied index is now lower than readState.Index' (duration: 7.655318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:58:42.185218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:58:41.87866Z","time spent":"306.554526ms","remote":"127.0.0.1:53068","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-20T12:58:42.185286Z","caller":"traceutil/trace.go:171","msg":"trace[1644516321] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"345.692811ms","start":"2026-05-20T12:58:41.839522Z","end":"2026-05-20T12:58:42.185215Z","steps":["trace[1644516321] 'process raft request' (duration: 345.495166ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:42.185295Z","caller":"traceutil/trace.go:171","msg":"trace[94877433] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"347.220544ms","start":"2026-05-20T12:58:41.838038Z","end":"2026-05-20T12:58:42.185259Z","steps":["trace[94877433] 'process raft request' (duration: 177.885255ms)","trace[94877433] 'compare' (duration: 168.197023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:58:42.185497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:58:41.838018Z","time spent":"347.378607ms","remote":"127.0.0.1:52808","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-05-20T12:58:42.185498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:58:41.839468Z","time spent":"345.912055ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T12:58:42.18563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.253407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-p65sh\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-05-20T12:58:42.185686Z","caller":"traceutil/trace.go:171","msg":"trace[35700252] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-p65sh; range_end:; response_count:1; response_revision:353; }","duration":"131.342399ms","start":"2026-05-20T12:58:42.054326Z","end":"2026-05-20T12:58:42.185668Z","steps":["trace[35700252] 'agreement among raft nodes before linearized reading' (duration: 131.242957ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:42.399525Z","caller":"traceutil/trace.go:171","msg":"trace[2026901948] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"207.660065ms","start":"2026-05-20T12:58:42.19169Z","end":"2026-05-20T12:58:42.39935Z","steps":["trace[2026901948] 'process raft request' (duration: 207.546742ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:08.434335Z","caller":"traceutil/trace.go:171","msg":"trace[1557948246] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"314.455007ms","start":"2026-05-20T12:59:08.119848Z","end":"2026-05-20T12:59:08.434303Z","steps":["trace[1557948246] 'read index received' (duration: 296.182508ms)","trace[1557948246] 'applied index is now lower than readState.Index' (duration: 18.271339ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T12:59:08.434782Z","caller":"traceutil/trace.go:171","msg":"trace[979024928] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"479.319627ms","start":"2026-05-20T12:59:07.955041Z","end":"2026-05-20T12:59:08.434361Z","steps":["trace[979024928] 'process raft request' (duration: 461.13496ms)","trace[979024928] 'compare' (duration: 17.674086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:59:08.435183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:07.955012Z","time spent":"480.024233ms","remote":"127.0.0.1:52634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T12:59:08.435283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.398597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rqr77\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-20T12:59:08.435442Z","caller":"traceutil/trace.go:171","msg":"trace[928395161] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rqr77; range_end:; response_count:1; response_revision:581; }","duration":"315.5591ms","start":"2026-05-20T12:59:08.119811Z","end":"2026-05-20T12:59:08.43537Z","steps":["trace[928395161] 'agreement among raft nodes before linearized reading' (duration: 314.794104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:08.435512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:08.119783Z","time spent":"315.714775ms","remote":"127.0.0.1:52742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rqr77\" "} {"level":"warn","ts":"2026-05-20T12:59:08.435663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.3586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T12:59:08.435791Z","caller":"traceutil/trace.go:171","msg":"trace[1082628104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:581; }","duration":"143.458493ms","start":"2026-05-20T12:59:08.29226Z","end":"2026-05-20T12:59:08.435718Z","steps":["trace[1082628104] 'agreement among raft nodes before linearized reading' (duration: 143.306709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:08.435853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.536794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T12:59:08.435918Z","caller":"traceutil/trace.go:171","msg":"trace[146780063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:581; }","duration":"143.636426ms","start":"2026-05-20T12:59:08.292254Z","end":"2026-05-20T12:59:08.43589Z","steps":["trace[146780063] 'agreement among raft nodes before linearized reading' (duration: 143.414582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:24.17052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.776666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T12:59:24.170742Z","caller":"traceutil/trace.go:171","msg":"trace[1442260008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"182.053152ms","start":"2026-05-20T12:59:23.988637Z","end":"2026-05-20T12:59:24.17069Z","steps":["trace[1442260008] 'range keys from in-memory index tree' (duration: 181.489359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:24.170653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.773732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T12:59:24.170922Z","caller":"traceutil/trace.go:171","msg":"trace[1988874630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"181.10764ms","start":"2026-05-20T12:59:23.989782Z","end":"2026-05-20T12:59:24.17089Z","steps":["trace[1988874630] 'range keys from in-memory index tree' (duration: 180.527797ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:58.804525Z","caller":"traceutil/trace.go:171","msg":"trace[2826387] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"129.79436ms","start":"2026-05-20T12:59:58.674705Z","end":"2026-05-20T12:59:58.8045Z","steps":["trace[2826387] 'process raft request' (duration: 129.605276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:59.037344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.989564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T12:59:59.037494Z","caller":"traceutil/trace.go:171","msg":"trace[768491325] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"361.549186ms","start":"2026-05-20T12:59:58.675931Z","end":"2026-05-20T12:59:59.03748Z","steps":["trace[768491325] 'process raft request' (duration: 256.214345ms)","trace[768491325] 'compare' (duration: 104.80315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:59:59.037552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:58.675909Z","time spent":"361.619387ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:59:59.037771Z","caller":"traceutil/trace.go:171","msg":"trace[893581475] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"229.143508ms","start":"2026-05-20T12:59:58.808621Z","end":"2026-05-20T12:59:59.037764Z","steps":["trace[893581475] 'process raft request' (duration: 228.814919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:53.518657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.906626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T13:00:53.518817Z","caller":"traceutil/trace.go:171","msg":"trace[1351685053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"211.122451ms","start":"2026-05-20T13:00:53.307669Z","end":"2026-05-20T13:00:53.518792Z","steps":["trace[1351685053] 'range keys from in-memory index tree' (duration: 210.68264ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:55.532881Z","caller":"traceutil/trace.go:171","msg":"trace[945811615] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"133.382625ms","start":"2026-05-20T13:00:55.39947Z","end":"2026-05-20T13:00:55.532853Z","steps":["trace[945811615] 'process raft request' (duration: 95.869446ms)","trace[945811615] 'compare' (duration: 37.360635ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:56.902595Z","caller":"traceutil/trace.go:171","msg":"trace[854726052] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"191.932956ms","start":"2026-05-20T13:00:56.710619Z","end":"2026-05-20T13:00:56.902551Z","steps":["trace[854726052] 'process raft request' (duration: 128.886145ms)","trace[854726052] 'compare' (duration: 62.836266ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:01:07.074527Z","caller":"traceutil/trace.go:171","msg":"trace[607411384] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"128.889385ms","start":"2026-05-20T13:01:06.94561Z","end":"2026-05-20T13:01:07.074499Z","steps":["trace[607411384] 'process raft request' (duration: 128.531966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.167917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.851643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:03:18.168007Z","caller":"traceutil/trace.go:171","msg":"trace[1479614081] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1896; }","duration":"443.950525ms","start":"2026-05-20T13:03:17.724027Z","end":"2026-05-20T13:03:18.167977Z","steps":["trace[1479614081] 'count revisions from in-memory index tree' (duration: 443.754992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.168039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:17.723971Z","time spent":"444.059279ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T13:03:18.168054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.492664ms","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-05-20T13:03:18.168161Z","caller":"traceutil/trace.go:171","msg":"trace[15776407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1896; }","duration":"312.648578ms","start":"2026-05-20T13:03:17.855488Z","end":"2026-05-20T13:03:18.168137Z","steps":["trace[15776407] 'range keys from in-memory index tree' (duration: 312.29557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.168217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:17.855463Z","time spent":"312.746419ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T13:03:18.168689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.25602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-20T13:03:18.168758Z","caller":"traceutil/trace.go:171","msg":"trace[1624119593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1896; }","duration":"503.367153ms","start":"2026-05-20T13:03:17.665373Z","end":"2026-05-20T13:03:18.16874Z","steps":["trace[1624119593] 'range keys from in-memory index tree' (duration: 502.393572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:18.16926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:17.665357Z","time spent":"503.884704ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T13:03:19.66712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372246585828584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:03:19.868862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566501937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-20T13:03:19.868952Z","caller":"traceutil/trace.go:171","msg":"trace[564943465] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1898; }","duration":"1.566628489s","start":"2026-05-20T13:03:18.302302Z","end":"2026-05-20T13:03:19.86893Z","steps":["trace[564943465] 'range keys from in-memory index tree' (duration: 1.566308072s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.868994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.302282Z","time spent":"1.566701831s","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-20T13:03:19.869077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540797941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-20T13:03:19.869133Z","caller":"traceutil/trace.go:171","msg":"trace[1669362779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1898; }","duration":"1.540884983s","start":"2026-05-20T13:03:18.328234Z","end":"2026-05-20T13:03:19.869119Z","steps":["trace[1669362779] 'range keys from in-memory index tree' (duration: 1.540687909s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.869162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.328221Z","time spent":"1.540932764s","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T13:03:19.869236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289318219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-20T13:03:19.869311Z","caller":"traceutil/trace.go:171","msg":"trace[1644611263] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1898; }","duration":"1.289441772s","start":"2026-05-20T13:03:18.579849Z","end":"2026-05-20T13:03:19.86929Z","steps":["trace[1644611263] 'range keys from in-memory index tree' (duration: 1.289154706s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.869349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.579824Z","time spent":"1.289515764s","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-20T13:03:19.875833Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.040801593s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T13:03:19.876074Z","caller":"traceutil/trace.go:171","msg":"trace[2031495463] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"1.041165081s","start":"2026-05-20T13:03:18.834895Z","end":"2026-05-20T13:03:19.87606Z","steps":["trace[2031495463] 'process raft request' (duration: 1.041070279s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.876191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:18.834863Z","time spent":"1.041261092s","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:03:19.876177Z","caller":"traceutil/trace.go:171","msg":"trace[367139904] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1971; }","duration":"709.183459ms","start":"2026-05-20T13:03:19.16697Z","end":"2026-05-20T13:03:19.876153Z","steps":["trace[367139904] 'read index received' (duration: 709.172649ms)","trace[367139904] 'applied index is now lower than readState.Index' (duration: 8.52µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:03:19.876444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.451136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T13:03:19.876605Z","caller":"traceutil/trace.go:171","msg":"trace[292486404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1899; }","duration":"709.654ms","start":"2026-05-20T13:03:19.166941Z","end":"2026-05-20T13:03:19.876595Z","steps":["trace[292486404] 'agreement among raft nodes before linearized reading' (duration: 709.319202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.876639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.166928Z","time spent":"709.702761ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T13:03:19.879808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.763319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-05-20T13:03:19.879844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.302806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-20T13:03:19.879861Z","caller":"traceutil/trace.go:171","msg":"trace[1858401008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1902; }","duration":"517.848211ms","start":"2026-05-20T13:03:19.362Z","end":"2026-05-20T13:03:19.879848Z","steps":["trace[1858401008] 'agreement among raft nodes before linearized reading' (duration: 517.715088ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:03:19.879881Z","caller":"traceutil/trace.go:171","msg":"trace[281819268] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1902; }","duration":"300.382217ms","start":"2026-05-20T13:03:19.579487Z","end":"2026-05-20T13:03:19.879869Z","steps":["trace[281819268] 'agreement among raft nodes before linearized reading' (duration: 300.194283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.879888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.361986Z","time spent":"517.894532ms","remote":"127.0.0.1:52808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T13:03:19.879907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.579469Z","time spent":"300.429388ms","remote":"127.0.0.1:52624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":140,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-20T13:03:19.879967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.672687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:03:19.879989Z","caller":"traceutil/trace.go:171","msg":"trace[788910337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1902; }","duration":"707.901221ms","start":"2026-05-20T13:03:19.172082Z","end":"2026-05-20T13:03:19.879983Z","steps":["trace[788910337] 'agreement among raft nodes before linearized reading' (duration: 707.527303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:19.880003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:19.172066Z","time spent":"707.933932ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}