{"level":"warn","ts":"2026-04-20T01:58:40.642156Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T01:58:40.642292Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T01:58:40.642581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T01:58:40.642611Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-20T01:58:40.642796Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T01:58:40.644001Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-04-20T01:58:40.644247Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T01:58:40.649075Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.373621ms"} {"level":"info","ts":"2026-04-20T01:58:40.655037Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-04-20T01:58:40.655158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T01:58:40.655229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-04-20T01:58:40.655265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T01:58:40.656411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-04-20T01:58:40.656497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-04-20T01:58:40.662201Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T01:58:40.663278Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T01:58:40.664138Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T01:58:40.665776Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T01:58:40.667174Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T01:58:40.667243Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T01:58:40.667289Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T01:58:40.668411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-04-20T01:58:40.668598Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-20T01:58:40.668611Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T01:58:40.670516Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T01:58:40.670759Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-20T01:58:40.670807Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-20T01:58:40.671409Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T01:58:40.671436Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T01:58:41.257484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T01:58:41.257556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T01:58:41.25761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-04-20T01:58:41.257636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-04-20T01:58:41.257661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-20T01:58:41.257684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-04-20T01:58:41.257697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-20T01:58:41.260097Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T01:58:41.260836Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T01:58:41.260881Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T01:58:41.261128Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T01:58:41.261315Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T01:58:41.261351Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T01:58:41.261363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T01:58:41.261686Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T01:58:41.261707Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T01:58:41.264144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-04-20T01:58:41.267362Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T01:58:50.629695Z","caller":"traceutil/trace.go:171","msg":"trace[15136086] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"120.601341ms","start":"2026-04-20T01:58:50.509074Z","end":"2026-04-20T01:58:50.629675Z","steps":["trace[15136086] 'process raft request' (duration: 120.300484ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T01:58:56.445826Z","caller":"traceutil/trace.go:171","msg":"trace[1976048643] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"243.520836ms","start":"2026-04-20T01:58:56.202278Z","end":"2026-04-20T01:58:56.445799Z","steps":["trace[1976048643] 'read index received' (duration: 243.473225ms)","trace[1976048643] 'applied index is now lower than readState.Index' (duration: 46.551µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T01:58:56.445933Z","caller":"traceutil/trace.go:171","msg":"trace[2005168073] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"307.137201ms","start":"2026-04-20T01:58:56.138767Z","end":"2026-04-20T01:58:56.445904Z","steps":["trace[2005168073] 'process raft request' (duration: 306.892445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:58:56.446109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.7095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:58:56.446178Z","caller":"traceutil/trace.go:171","msg":"trace[1659682339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"243.946866ms","start":"2026-04-20T01:58:56.202216Z","end":"2026-04-20T01:58:56.446163Z","steps":["trace[1659682339] 'agreement among raft nodes before linearized reading' (duration: 243.68908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:58:56.446636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:58:56.138739Z","time spent":"307.292725ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4406,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T01:58:56.66862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.353441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T01:58:56.66884Z","caller":"traceutil/trace.go:171","msg":"trace[1422568163] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"405.186189ms","start":"2026-04-20T01:58:56.263622Z","end":"2026-04-20T01:58:56.668808Z","steps":["trace[1422568163] 'process raft request' (duration: 299.452597ms)","trace[1422568163] 'compare' (duration: 105.215418ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T01:58:56.668811Z","caller":"traceutil/trace.go:171","msg":"trace[584563208] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"221.421002ms","start":"2026-04-20T01:58:56.447369Z","end":"2026-04-20T01:58:56.66879Z","steps":["trace[584563208] 'read index received' (duration: 115.731582ms)","trace[584563208] 'applied index is now lower than readState.Index' (duration: 105.68739ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T01:58:56.668957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:58:56.263562Z","time spent":"405.347883ms","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T01:58:56.668963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.568976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:58:56.669054Z","caller":"traceutil/trace.go:171","msg":"trace[348712945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"221.650928ms","start":"2026-04-20T01:58:56.447364Z","end":"2026-04-20T01:58:56.669015Z","steps":["trace[348712945] 'agreement among raft nodes before linearized reading' (duration: 221.522654ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T01:59:12.111996Z","caller":"traceutil/trace.go:171","msg":"trace[682572234] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"102.576258ms","start":"2026-04-20T01:59:12.009404Z","end":"2026-04-20T01:59:12.11198Z","steps":["trace[682572234] 'process raft request' (duration: 102.487656ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T01:59:12.115577Z","caller":"traceutil/trace.go:171","msg":"trace[2143561828] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"101.902082ms","start":"2026-04-20T01:59:12.013658Z","end":"2026-04-20T01:59:12.11556Z","steps":["trace[2143561828] 'process raft request' (duration: 101.114552ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T01:59:18.683909Z","caller":"traceutil/trace.go:171","msg":"trace[1711485068] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"298.565598ms","start":"2026-04-20T01:59:18.385321Z","end":"2026-04-20T01:59:18.683887Z","steps":["trace[1711485068] 'process raft request' (duration: 298.144998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:25.479324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052133093643779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T01:59:25.980321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052133093643779,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T01:59:26.363067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394169151s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T01:59:26.416012Z","caller":"traceutil/trace.go:171","msg":"trace[1582143197] transaction","detail":"{read_only:false; number_of_response:1; response_revision:711; }","duration":"1.449039908s","start":"2026-04-20T01:59:24.966945Z","end":"2026-04-20T01:59:26.415984Z","steps":["trace[1582143197] 'process raft request' (duration: 1.396272164s)","trace[1582143197] 'compare' (duration: 52.698832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T01:59:26.416799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:24.966935Z","time spent":"1.449789131s","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30,"response count":0,"response size":4295,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T01:59:26.41992Z","caller":"traceutil/trace.go:171","msg":"trace[954242384] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"1.445138432s","start":"2026-04-20T01:59:24.974769Z","end":"2026-04-20T01:59:26.419908Z","steps":["trace[954242384] 'process raft request' (duration: 1.445000588s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.42001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:24.974752Z","time spent":"1.445210184s","remote":"127.0.0.1:49888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3820,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T01:59:26.420283Z","caller":"traceutil/trace.go:171","msg":"trace[1554063939] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"1.445096632s","start":"2026-04-20T01:59:24.975164Z","end":"2026-04-20T01:59:26.42026Z","steps":["trace[1554063939] 'process raft request' (duration: 1.444706271s)"],"step_count":1} {"level":"info","ts":"2026-04-20T01:59:26.420351Z","caller":"traceutil/trace.go:171","msg":"trace[840007607] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:736; }","duration":"1.441626535s","start":"2026-04-20T01:59:24.978714Z","end":"2026-04-20T01:59:26.420341Z","steps":["trace[840007607] 'read index received' (duration: 1.384526998s)","trace[840007607] 'applied index is now lower than readState.Index' (duration: 57.098617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T01:59:26.420411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:24.975157Z","time spent":"1.445183565s","remote":"127.0.0.1:49888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T01:59:26.42054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441761549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:26.420573Z","caller":"traceutil/trace.go:171","msg":"trace[2040114806] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:714; }","duration":"1.44182136s","start":"2026-04-20T01:59:24.978744Z","end":"2026-04-20T01:59:26.420566Z","steps":["trace[2040114806] 'agreement among raft nodes before linearized reading' (duration: 1.441772359s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.420556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441828531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T01:59:26.420597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:24.978727Z","time spent":"1.441862402s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-04-20T01:59:26.420636Z","caller":"traceutil/trace.go:171","msg":"trace[579911268] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:714; }","duration":"1.441929624s","start":"2026-04-20T01:59:24.978685Z","end":"2026-04-20T01:59:26.420615Z","steps":["trace[579911268] 'agreement among raft nodes before linearized reading' (duration: 1.441814361s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.420725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348479873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T01:59:26.420668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348388409s","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-04-20T01:59:26.420753Z","caller":"traceutil/trace.go:171","msg":"trace[792139956] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:714; }","duration":"1.348517603s","start":"2026-04-20T01:59:25.072229Z","end":"2026-04-20T01:59:26.420747Z","steps":["trace[792139956] 'agreement among raft nodes before linearized reading' (duration: 1.348471112s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.420765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.685614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:26.420785Z","caller":"traceutil/trace.go:171","msg":"trace[745175580] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:714; }","duration":"1.348514083s","start":"2026-04-20T01:59:25.072244Z","end":"2026-04-20T01:59:26.420758Z","steps":["trace[745175580] 'agreement among raft nodes before linearized reading' (duration: 1.348376329s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.420716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:24.978674Z","time spent":"1.442023336s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-04-20T01:59:26.42082Z","caller":"traceutil/trace.go:171","msg":"trace[586122501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"221.756766ms","start":"2026-04-20T01:59:26.199041Z","end":"2026-04-20T01:59:26.420798Z","steps":["trace[586122501] 'agreement among raft nodes before linearized reading' (duration: 221.704624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.420828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.072241Z","time spent":"1.348571705s","remote":"127.0.0.1:39842","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-04-20T01:59:26.42089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348672017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:26.420916Z","caller":"traceutil/trace.go:171","msg":"trace[755321806] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:714; }","duration":"1.348709739s","start":"2026-04-20T01:59:25.0722Z","end":"2026-04-20T01:59:26.42091Z","steps":["trace[755321806] 'agreement among raft nodes before linearized reading' (duration: 1.348668588s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.42094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.072193Z","time spent":"1.34873769s","remote":"127.0.0.1:39842","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\" limit:10 "} {"level":"info","ts":"2026-04-20T01:59:26.420997Z","caller":"traceutil/trace.go:171","msg":"trace[1291217973] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"773.013736ms","start":"2026-04-20T01:59:25.64797Z","end":"2026-04-20T01:59:26.420984Z","steps":["trace[1291217973] 'process raft request' (duration: 772.196333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.421009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.590644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-20T01:59:26.421065Z","caller":"traceutil/trace.go:171","msg":"trace[268709268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"427.686196ms","start":"2026-04-20T01:59:25.993371Z","end":"2026-04-20T01:59:26.421057Z","steps":["trace[268709268] 'agreement among raft nodes before linearized reading' (duration: 427.549202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.421092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.993342Z","time spent":"427.742427ms","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-20T01:59:26.421095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.647945Z","time spent":"773.112559ms","remote":"127.0.0.1:49874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T01:59:26.421119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350264054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T01:59:26.421145Z","caller":"traceutil/trace.go:171","msg":"trace[1185132565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"1.350308444s","start":"2026-04-20T01:59:25.07083Z","end":"2026-04-20T01:59:26.421139Z","steps":["trace[1185132565] 'agreement among raft nodes before linearized reading' (duration: 1.350184251s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.421163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.070814Z","time spent":"1.350344426s","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T01:59:26.420796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.072226Z","time spent":"1.348564795s","remote":"127.0.0.1:39842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T01:59:26.421302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.742977ms","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-04-20T01:59:26.42133Z","caller":"traceutil/trace.go:171","msg":"trace[2019333674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"609.80547ms","start":"2026-04-20T01:59:25.811518Z","end":"2026-04-20T01:59:26.421323Z","steps":["trace[2019333674] 'agreement among raft nodes before linearized reading' (duration: 609.726617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.421338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410208313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T01:59:26.42135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.811498Z","time spent":"609.84711ms","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T01:59:26.421376Z","caller":"traceutil/trace.go:171","msg":"trace[1678019781] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:714; }","duration":"1.410256754s","start":"2026-04-20T01:59:25.011102Z","end":"2026-04-20T01:59:26.421359Z","steps":["trace[1678019781] 'agreement among raft nodes before linearized reading' (duration: 1.410212712s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.421402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.011087Z","time spent":"1.410307985s","remote":"127.0.0.1:39834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-04-20T01:59:26.421482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133063585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-drmw4\" ","response":"range_response_count:1 size:18516"} {"level":"warn","ts":"2026-04-20T01:59:26.421528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410964693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:26.421531Z","caller":"traceutil/trace.go:171","msg":"trace[166645246] range","detail":"{range_begin:/registry/pods/kube-system/cilium-drmw4; range_end:; response_count:1; response_revision:714; }","duration":"1.133157727s","start":"2026-04-20T01:59:25.288367Z","end":"2026-04-20T01:59:26.421525Z","steps":["trace[166645246] 'agreement among raft nodes before linearized reading' (duration: 1.133063275s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.42156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.288346Z","time spent":"1.133208769s","remote":"127.0.0.1:49888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18539,"request content":"key:\"/registry/pods/kube-system/cilium-drmw4\" "} {"level":"warn","ts":"2026-04-20T01:59:26.42168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.173971794s","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-04-20T01:59:26.421955Z","caller":"traceutil/trace.go:171","msg":"trace[1583901293] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:714; }","duration":"1.411035606s","start":"2026-04-20T01:59:25.010508Z","end":"2026-04-20T01:59:26.421543Z","steps":["trace[1583901293] 'agreement among raft nodes before linearized reading' (duration: 1.410997735s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.421991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.010486Z","time spent":"1.411496698s","remote":"127.0.0.1:39834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T01:59:26.422072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411732595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:26.422094Z","caller":"traceutil/trace.go:171","msg":"trace[1891483057] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:714; }","duration":"1.411773077s","start":"2026-04-20T01:59:25.010315Z","end":"2026-04-20T01:59:26.422088Z","steps":["trace[1891483057] 'agreement among raft nodes before linearized reading' (duration: 1.411739146s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.42211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.010298Z","time spent":"1.411807428s","remote":"127.0.0.1:39834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T01:59:26.422183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441593238s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:26.422201Z","caller":"traceutil/trace.go:171","msg":"trace[1627433041] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:714; }","duration":"1.44162764s","start":"2026-04-20T01:59:24.980568Z","end":"2026-04-20T01:59:26.422196Z","steps":["trace[1627433041] 'agreement among raft nodes before linearized reading' (duration: 1.441600579s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.422216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:24.98056Z","time spent":"1.44165225s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" limit:500 "} {"level":"warn","ts":"2026-04-20T01:59:26.422281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443494878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:26.422341Z","caller":"traceutil/trace.go:171","msg":"trace[1280341430] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:714; }","duration":"1.443603402s","start":"2026-04-20T01:59:24.97873Z","end":"2026-04-20T01:59:26.422333Z","steps":["trace[1280341430] 'agreement among raft nodes before linearized reading' (duration: 1.44352612s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.422371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:24.978691Z","time spent":"1.443670213s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"info","ts":"2026-04-20T01:59:26.422312Z","caller":"traceutil/trace.go:171","msg":"trace[2065073233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"1.174018906s","start":"2026-04-20T01:59:25.247678Z","end":"2026-04-20T01:59:26.421697Z","steps":["trace[2065073233] 'agreement among raft nodes before linearized reading' (duration: 1.173962414s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:26.42258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:25.247655Z","time spent":"1.174917521s","remote":"127.0.0.1:49994","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-04-20T01:59:28.325899Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052133093643812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T01:59:29.30618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052133093643812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T01:59:29.836085Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052133093643812,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T01:59:30.201054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999814927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T01:59:30.201131Z","caller":"traceutil/trace.go:171","msg":"trace[1872171754] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999972493s","start":"2026-04-20T01:59:28.201143Z","end":"2026-04-20T01:59:30.201116Z","steps":["trace[1872171754] 'agreement among raft nodes before linearized reading' (duration: 1.999852719s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.201163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:28.201121Z","time spent":"2.000036414s","remote":"127.0.0.1:49722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T01:59:30.302166Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.386934111s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T01:59:30.302656Z","caller":"traceutil/trace.go:171","msg":"trace[1538648794] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"3.387497237s","start":"2026-04-20T01:59:26.915133Z","end":"2026-04-20T01:59:30.30263Z","steps":["trace[1538648794] 'process raft request' (duration: 3.387338253s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.302857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:26.915108Z","time spent":"3.387656661s","remote":"127.0.0.1:49994","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-04-20T01:59:30.350269Z","caller":"traceutil/trace.go:171","msg":"trace[1970414089] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"3.316856699s","start":"2026-04-20T01:59:27.033357Z","end":"2026-04-20T01:59:30.350214Z","steps":["trace[1970414089] 'process raft request' (duration: 3.313208776s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.350532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:27.03334Z","time spent":"3.317049375s","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T01:59:30.352374Z","caller":"traceutil/trace.go:171","msg":"trace[1606895409] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:754; }","duration":"2.526734785s","start":"2026-04-20T01:59:27.825614Z","end":"2026-04-20T01:59:30.352349Z","steps":["trace[1606895409] 'read index received' (duration: 2.476902085s)","trace[1606895409] 'applied index is now lower than readState.Index' (duration: 49.83107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T01:59:30.352507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.526868388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:30.352571Z","caller":"traceutil/trace.go:171","msg":"trace[963976699] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:729; }","duration":"2.526988911s","start":"2026-04-20T01:59:27.825566Z","end":"2026-04-20T01:59:30.352555Z","steps":["trace[963976699] 'agreement among raft nodes before linearized reading' (duration: 2.526878418s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.352601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:27.82554Z","time spent":"2.527054475s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-04-20T01:59:30.352622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.912686817s","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-04-20T01:59:30.352662Z","caller":"traceutil/trace.go:171","msg":"trace[1971518779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:729; }","duration":"1.912750039s","start":"2026-04-20T01:59:28.439899Z","end":"2026-04-20T01:59:30.352649Z","steps":["trace[1971518779] 'agreement among raft nodes before linearized reading' (duration: 1.912615004s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.352688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913176721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T01:59:30.352739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:28.439889Z","time spent":"1.91279209s","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T01:59:30.35276Z","caller":"traceutil/trace.go:171","msg":"trace[1400937656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:729; }","duration":"1.913282134s","start":"2026-04-20T01:59:28.43947Z","end":"2026-04-20T01:59:30.352752Z","steps":["trace[1400937656] 'agreement among raft nodes before linearized reading' (duration: 1.913174751s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.352536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.526818377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T01:59:30.352788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:28.439447Z","time spent":"1.913334235s","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-20T01:59:30.352807Z","caller":"traceutil/trace.go:171","msg":"trace[1333586391] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:729; }","duration":"2.527141987s","start":"2026-04-20T01:59:27.825655Z","end":"2026-04-20T01:59:30.352797Z","steps":["trace[1333586391] 'agreement among raft nodes before linearized reading' (duration: 2.526825687s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.352841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:27.82563Z","time spent":"2.527196438s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "} {"level":"warn","ts":"2026-04-20T01:59:30.352889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.025105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T01:59:30.352915Z","caller":"traceutil/trace.go:171","msg":"trace[1451443330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"151.076807ms","start":"2026-04-20T01:59:30.20183Z","end":"2026-04-20T01:59:30.352906Z","steps":["trace[1451443330] 'agreement among raft nodes before linearized reading' (duration: 151.033296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.353011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913627853s","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-04-20T01:59:30.353059Z","caller":"traceutil/trace.go:171","msg":"trace[827560586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"1.913697095s","start":"2026-04-20T01:59:28.439355Z","end":"2026-04-20T01:59:30.353052Z","steps":["trace[827560586] 'agreement among raft nodes before linearized reading' (duration: 1.913615333s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.353085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:28.439342Z","time spent":"1.913738036s","remote":"127.0.0.1:49994","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-04-20T01:59:30.35263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.913077917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T01:59:30.353179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046042558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-drmw4.18a7ee1867f2a5c5\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-04-20T01:59:30.353199Z","caller":"traceutil/trace.go:171","msg":"trace[111478048] range","detail":"{range_begin:/registry/events/kube-system/cilium-drmw4.18a7ee1867f2a5c5; range_end:; response_count:1; response_revision:729; }","duration":"1.046097759s","start":"2026-04-20T01:59:29.307094Z","end":"2026-04-20T01:59:30.353192Z","steps":["trace[111478048] 'agreement among raft nodes before linearized reading' (duration: 1.046041208s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.353218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:29.307068Z","time spent":"1.046144401s","remote":"127.0.0.1:49784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":818,"request content":"key:\"/registry/events/kube-system/cilium-drmw4.18a7ee1867f2a5c5\" "} {"level":"info","ts":"2026-04-20T01:59:30.353197Z","caller":"traceutil/trace.go:171","msg":"trace[465463477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:729; }","duration":"1.913623483s","start":"2026-04-20T01:59:28.43952Z","end":"2026-04-20T01:59:30.353144Z","steps":["trace[465463477] 'agreement among raft nodes before linearized reading' (duration: 1.913032365s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T01:59:30.353304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T01:59:28.439503Z","time spent":"1.913763947s","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T02:00:09.035535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052133093644375,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T02:00:09.464301Z","caller":"traceutil/trace.go:171","msg":"trace[1441722062] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"929.288395ms","start":"2026-04-20T02:00:08.534988Z","end":"2026-04-20T02:00:09.464276Z","steps":["trace[1441722062] 'read index received' (duration: 929.214734ms)","trace[1441722062] 'applied index is now lower than readState.Index' (duration: 72.761µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:00:09.46442Z","caller":"traceutil/trace.go:171","msg":"trace[1300330442] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"954.165792ms","start":"2026-04-20T02:00:08.510216Z","end":"2026-04-20T02:00:09.464382Z","steps":["trace[1300330442] 'process raft request' (duration: 953.928056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:00:09.464585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:00:08.510189Z","time spent":"954.314235ms","remote":"127.0.0.1:49994","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-04-20T02:00:09.464714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.734258ms","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-04-20T02:00:09.464772Z","caller":"traceutil/trace.go:171","msg":"trace[1059337129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"929.82415ms","start":"2026-04-20T02:00:08.534923Z","end":"2026-04-20T02:00:09.464747Z","steps":["trace[1059337129] 'agreement among raft nodes before linearized reading' (duration: 929.479951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:00:09.464818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:00:08.534901Z","time spent":"929.907872ms","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T02:00:09.469832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.814187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:00:09.469861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.004531ms","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-04-20T02:00:09.46988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.253435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T02:00:09.469895Z","caller":"traceutil/trace.go:171","msg":"trace[329614499] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1013; }","duration":"653.93271ms","start":"2026-04-20T02:00:08.81595Z","end":"2026-04-20T02:00:09.469883Z","steps":["trace[329614499] 'agreement among raft nodes before linearized reading' (duration: 653.816827ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:00:09.469916Z","caller":"traceutil/trace.go:171","msg":"trace[1378855033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"878.104954ms","start":"2026-04-20T02:00:08.591798Z","end":"2026-04-20T02:00:09.469903Z","steps":["trace[1378855033] 'agreement among raft nodes before linearized reading' (duration: 877.935409ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:00:09.469919Z","caller":"traceutil/trace.go:171","msg":"trace[234815200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"817.312816ms","start":"2026-04-20T02:00:08.652596Z","end":"2026-04-20T02:00:09.469909Z","steps":["trace[234815200] 'agreement among raft nodes before linearized reading' (duration: 817.199673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:00:09.469915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.337904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-20T02:00:09.46993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:00:08.815931Z","time spent":"653.990622ms","remote":"127.0.0.1:49858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:00:09.469949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:00:08.652589Z","time spent":"817.353657ms","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T02:00:09.46995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:00:08.591781Z","time spent":"878.162805ms","remote":"127.0.0.1:49994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T02:00:09.469959Z","caller":"traceutil/trace.go:171","msg":"trace[1523066146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"821.422626ms","start":"2026-04-20T02:00:08.648525Z","end":"2026-04-20T02:00:09.469947Z","steps":["trace[1523066146] 'agreement among raft nodes before linearized reading' (duration: 821.307023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:00:09.469988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:00:08.648504Z","time spent":"821.476078ms","remote":"127.0.0.1:49994","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\" "}