{"level":"warn","ts":"2026-03-14T06:56:13.877657Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T06:56:13.877739Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-03-14T06:56:13.877805Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-14T06:56:13.877817Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-03-14T06:56:13.877831Z","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-03-14T06:56:13.878627Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-03-14T06:56:13.878724Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-03-14T06:56:13.890514Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.57791ms"} {"level":"info","ts":"2026-03-14T06:56:13.901215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-03-14T06:56:13.901296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T06:56:13.901319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-03-14T06:56:13.901333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T06:56:13.901349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-03-14T06:56:13.901388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-03-14T06:56:13.916124Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T06:56:13.921206Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T06:56:13.923272Z","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-03-14T06:56:13.92657Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T06:56:13.928065Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T06:56:13.928658Z","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-03-14T06:56:13.929045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-03-14T06:56:13.929332Z","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-03-14T06:56:13.929356Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-03-14T06:56:13.929384Z","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-03-14T06:56:13.93353Z","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-03-14T06:56:13.933635Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-03-14T06:56:13.933667Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-03-14T06:56:13.933778Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T06:56:13.933824Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T06:56:14.503223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T06:56:14.503293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T06:56:14.503364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-03-14T06:56:14.503383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-03-14T06:56:14.5034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-03-14T06:56:14.503417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-03-14T06:56:14.503436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-03-14T06:56:14.50454Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:56:14.505651Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T06:56:14.505859Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T06:56:14.506029Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:56:14.506139Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:56:14.506179Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T06:56:14.505905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T06:56:14.509615Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T06:56:14.513589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-03-14T06:56:14.515042Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T06:56:14.515073Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-03-14T06:56:40.791088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.800871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T06:56:40.791332Z","caller":"traceutil/trace.go:171","msg":"trace[1410114407] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"503.235174ms","start":"2026-03-14T06:56:40.288053Z","end":"2026-03-14T06:56:40.791288Z","steps":["trace[1410114407] 'process raft request' (duration: 14.676426ms)","trace[1410114407] 'compare' (duration: 487.482486ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:56:40.791517Z","caller":"traceutil/trace.go:171","msg":"trace[1619398243] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"499.465847ms","start":"2026-03-14T06:56:40.29201Z","end":"2026-03-14T06:56:40.791476Z","steps":["trace[1619398243] 'process raft request' (duration: 499.207961ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:40.791593Z","caller":"traceutil/trace.go:171","msg":"trace[401521883] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"494.493459ms","start":"2026-03-14T06:56:40.297081Z","end":"2026-03-14T06:56:40.791574Z","steps":["trace[401521883] 'process raft request' (duration: 494.364471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:40.791605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.288028Z","time spent":"503.468859ms","remote":"127.0.0.1:54820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4339,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:40.79171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.292004Z","time spent":"499.631784ms","remote":"127.0.0.1:54802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:40.791774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.297063Z","time spent":"494.580518ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:41.011973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764022604190196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T06:56:41.513106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764022604190196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T06:56:41.677555Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.348224305s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T06:56:41.718243Z","caller":"traceutil/trace.go:171","msg":"trace[2052080190] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"1.38963492s","start":"2026-03-14T06:56:40.328576Z","end":"2026-03-14T06:56:41.718211Z","steps":["trace[2052080190] 'process raft request' (duration: 1.349508294s)","trace[2052080190] 'compare' (duration: 38.840857ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:56:41.718499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.32854Z","time spent":"1.389882895s","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T06:56:41.749608Z","caller":"traceutil/trace.go:171","msg":"trace[1753607510] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:479; }","duration":"1.238246243s","start":"2026-03-14T06:56:40.511305Z","end":"2026-03-14T06:56:41.749551Z","steps":["trace[1753607510] 'read index received' (duration: 1.166492986s)","trace[1753607510] 'applied index is now lower than readState.Index' (duration: 71.752147ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:56:41.749777Z","caller":"traceutil/trace.go:171","msg":"trace[1606290702] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"950.032425ms","start":"2026-03-14T06:56:40.799737Z","end":"2026-03-14T06:56:41.749769Z","steps":["trace[1606290702] 'process raft request' (duration: 949.635761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:41.749883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.79972Z","time spent":"950.092143ms","remote":"127.0.0.1:54802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4656,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:41.750053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238717946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-14T06:56:41.750097Z","caller":"traceutil/trace.go:171","msg":"trace[88277] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:465; }","duration":"1.238839325s","start":"2026-03-14T06:56:40.51125Z","end":"2026-03-14T06:56:41.750089Z","steps":["trace[88277] 'agreement among raft nodes before linearized reading' (duration: 1.238732125s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:41.750128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.511229Z","time spent":"1.238893204s","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-03-14T06:56:41.750377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.633462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:41.75045Z","caller":"traceutil/trace.go:171","msg":"trace[1551691551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"664.706681ms","start":"2026-03-14T06:56:41.085721Z","end":"2026-03-14T06:56:41.750427Z","steps":["trace[1551691551] 'agreement among raft nodes before linearized reading' (duration: 664.603653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:41.75045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.238298603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-03-14T06:56:41.750474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:41.085658Z","time spent":"664.811789ms","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T06:56:41.750531Z","caller":"traceutil/trace.go:171","msg":"trace[661031593] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:465; }","duration":"1.238459261s","start":"2026-03-14T06:56:40.512048Z","end":"2026-03-14T06:56:41.750507Z","steps":["trace[661031593] 'agreement among raft nodes before linearized reading' (duration: 1.238197415s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:41.750586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.512017Z","time spent":"1.238561979s","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-03-14T06:56:41.750624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.702364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xsck9\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-03-14T06:56:41.75071Z","caller":"traceutil/trace.go:171","msg":"trace[1615410167] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-xsck9; range_end:; response_count:1; response_revision:465; }","duration":"953.864212ms","start":"2026-03-14T06:56:40.796834Z","end":"2026-03-14T06:56:41.750698Z","steps":["trace[1615410167] 'agreement among raft nodes before linearized reading' (duration: 953.526137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:41.750778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:40.796771Z","time spent":"953.99762ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":3660,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xsck9\" "} {"level":"info","ts":"2026-03-14T06:56:41.921148Z","caller":"traceutil/trace.go:171","msg":"trace[1505361502] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"128.999934ms","start":"2026-03-14T06:56:41.792118Z","end":"2026-03-14T06:56:41.921118Z","steps":["trace[1505361502] 'read index received' (duration: 105.282078ms)","trace[1505361502] 'applied index is now lower than readState.Index' (duration: 23.716586ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:56:41.921203Z","caller":"traceutil/trace.go:171","msg":"trace[1434566407] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"161.01494ms","start":"2026-03-14T06:56:41.760145Z","end":"2026-03-14T06:56:41.92116Z","steps":["trace[1434566407] 'process raft request' (duration: 137.286424ms)","trace[1434566407] 'compare' (duration: 23.363711ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:56:41.921531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.387028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-03-14T06:56:41.921594Z","caller":"traceutil/trace.go:171","msg":"trace[478092904] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:466; }","duration":"129.470597ms","start":"2026-03-14T06:56:41.792109Z","end":"2026-03-14T06:56:41.92158Z","steps":["trace[478092904] 'agreement among raft nodes before linearized reading' (duration: 129.110313ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:42.377152Z","caller":"traceutil/trace.go:171","msg":"trace[1122487154] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"116.389499ms","start":"2026-03-14T06:56:42.260738Z","end":"2026-03-14T06:56:42.377127Z","steps":["trace[1122487154] 'process raft request' (duration: 116.245981ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:42.378702Z","caller":"traceutil/trace.go:171","msg":"trace[384066209] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"116.274492ms","start":"2026-03-14T06:56:42.262406Z","end":"2026-03-14T06:56:42.37868Z","steps":["trace[384066209] 'process raft request' (duration: 116.182643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:45.585624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764022604190253,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T06:56:46.086575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764022604190253,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T06:56:46.323143Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.330689446s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T06:56:46.323529Z","caller":"traceutil/trace.go:171","msg":"trace[625130297] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.332891998s","start":"2026-03-14T06:56:44.990613Z","end":"2026-03-14T06:56:46.323505Z","steps":["trace[625130297] 'process raft request' (duration: 1.33271681s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:46.323655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:44.990572Z","time spent":"1.333019997s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T06:56:47.085667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000079946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-14T06:56:47.08575Z","caller":"traceutil/trace.go:171","msg":"trace[1437888962] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000210704s","start":"2026-03-14T06:56:45.085525Z","end":"2026-03-14T06:56:47.085736Z","steps":["trace[1437888962] 'agreement among raft nodes before linearized reading' (duration: 2.000108776s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.085784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.085512Z","time spent":"2.000265894s","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T06:56:47.191861Z","caller":"traceutil/trace.go:171","msg":"trace[938092501] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"2.006988313s","start":"2026-03-14T06:56:45.184841Z","end":"2026-03-14T06:56:47.191829Z","steps":["trace[938092501] 'process raft request' (duration: 2.006783055s)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:47.19194Z","caller":"traceutil/trace.go:171","msg":"trace[1159526789] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.805976675s","start":"2026-03-14T06:56:45.385935Z","end":"2026-03-14T06:56:47.191912Z","steps":["trace[1159526789] 'process raft request' (duration: 1.805817757s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.191989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.184804Z","time spent":"2.007126562s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T06:56:47.191977Z","caller":"traceutil/trace.go:171","msg":"trace[1677890123] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.606163351s","start":"2026-03-14T06:56:45.585774Z","end":"2026-03-14T06:56:47.191938Z","steps":["trace[1677890123] 'process raft request' (duration: 1.606011483s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.192023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.385911Z","time spent":"1.806064444s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T06:56:47.192044Z","caller":"traceutil/trace.go:171","msg":"trace[654208042] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.406727423s","start":"2026-03-14T06:56:45.785272Z","end":"2026-03-14T06:56:47.191999Z","steps":["trace[654208042] 'process raft request' (duration: 1.406538455s)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:47.191859Z","caller":"traceutil/trace.go:171","msg":"trace[951700436] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:515; }","duration":"2.106273709s","start":"2026-03-14T06:56:45.085563Z","end":"2026-03-14T06:56:47.191837Z","steps":["trace[951700436] 'read index received' (duration: 1.237842782s)","trace[951700436] 'applied index is now lower than readState.Index' (duration: 868.429767ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:56:47.19213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.585756Z","time spent":"1.606305949s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T06:56:47.19216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.785245Z","time spent":"1.406867621s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T06:56:47.654223Z","caller":"traceutil/trace.go:171","msg":"trace[304716817] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:520; }","duration":"462.050548ms","start":"2026-03-14T06:56:47.192146Z","end":"2026-03-14T06:56:47.654197Z","steps":["trace[304716817] 'read index received' (duration: 461.786261ms)","trace[304716817] 'applied index is now lower than readState.Index' (duration: 263.137µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:56:47.654363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470497748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:47.654408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698179731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-14T06:56:47.65443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070872319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-14T06:56:47.654461Z","caller":"traceutil/trace.go:171","msg":"trace[1837057768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"1.698272709s","start":"2026-03-14T06:56:45.956174Z","end":"2026-03-14T06:56:47.654447Z","steps":["trace[1837057768] 'agreement among raft nodes before linearized reading' (duration: 1.69818054s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.654405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670225662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:47.654496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122147475s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:47.654487Z","caller":"traceutil/trace.go:171","msg":"trace[1364288867] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:505; }","duration":"1.070958898s","start":"2026-03-14T06:56:46.583511Z","end":"2026-03-14T06:56:47.654469Z","steps":["trace[1364288867] 'agreement among raft nodes before linearized reading' (duration: 1.070726161s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.654516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.956153Z","time spent":"1.698351209s","remote":"127.0.0.1:54596","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-03-14T06:56:47.654534Z","caller":"traceutil/trace.go:171","msg":"trace[84565380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"1.122206415s","start":"2026-03-14T06:56:46.532316Z","end":"2026-03-14T06:56:47.654522Z","steps":["trace[84565380] 'agreement among raft nodes before linearized reading' (duration: 1.122149365s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.65456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.583496Z","time spent":"1.071051047s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-03-14T06:56:47.654632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2577538s","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-03-14T06:56:47.654609Z","caller":"traceutil/trace.go:171","msg":"trace[1227415660] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:505; }","duration":"1.670462609s","start":"2026-03-14T06:56:45.984124Z","end":"2026-03-14T06:56:47.654586Z","steps":["trace[1227415660] 'agreement among raft nodes before linearized reading' (duration: 1.670207572s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.65474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270850106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-03-14T06:56:47.65473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.984108Z","time spent":"1.670604327s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-14T06:56:47.654783Z","caller":"traceutil/trace.go:171","msg":"trace[1718110132] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:505; }","duration":"1.270914996s","start":"2026-03-14T06:56:46.383851Z","end":"2026-03-14T06:56:47.654766Z","steps":["trace[1718110132] 'agreement among raft nodes before linearized reading' (duration: 1.270846547s)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:47.654785Z","caller":"traceutil/trace.go:171","msg":"trace[1430442631] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.318252719s","start":"2026-03-14T06:56:46.336516Z","end":"2026-03-14T06:56:47.654769Z","steps":["trace[1430442631] 'process raft request' (duration: 1.317463488s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.654821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.383837Z","time spent":"1.270973284s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5609,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-03-14T06:56:47.654856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.492290031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xsck9\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-03-14T06:56:47.654907Z","caller":"traceutil/trace.go:171","msg":"trace[653713759] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-xsck9; range_end:; response_count:1; response_revision:505; }","duration":"2.49234069s","start":"2026-03-14T06:56:45.162554Z","end":"2026-03-14T06:56:47.654895Z","steps":["trace[653713759] 'agreement among raft nodes before linearized reading' (duration: 2.492199072s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.654911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.336502Z","time spent":"1.318334287s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:47.654953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.162493Z","time spent":"2.492450019s","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4911,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-xsck9\" "} {"level":"warn","ts":"2026-03-14T06:56:47.654993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662335761s","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-03-14T06:56:47.65505Z","caller":"traceutil/trace.go:171","msg":"trace[1984038176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.662392031s","start":"2026-03-14T06:56:45.992644Z","end":"2026-03-14T06:56:47.655036Z","steps":["trace[1984038176] 'agreement among raft nodes before linearized reading' (duration: 1.662291272s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.655099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.992617Z","time spent":"1.66247078s","remote":"127.0.0.1:54596","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-03-14T06:56:47.655117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46097234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-14T06:56:47.655166Z","caller":"traceutil/trace.go:171","msg":"trace[8828105] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.46104597s","start":"2026-03-14T06:56:46.194107Z","end":"2026-03-14T06:56:47.655153Z","steps":["trace[8828105] 'agreement among raft nodes before linearized reading' (duration: 1.460932041s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.65521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.683046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-14T06:56:47.655218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.194097Z","time spent":"1.461109809s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T06:56:47.655235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664339188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T06:56:47.655264Z","caller":"traceutil/trace.go:171","msg":"trace[752415245] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.260594998s","start":"2026-03-14T06:56:46.394652Z","end":"2026-03-14T06:56:47.655247Z","steps":["trace[752415245] 'process raft request' (duration: 1.25948956s)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:47.654436Z","caller":"traceutil/trace.go:171","msg":"trace[157700512] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:505; }","duration":"1.470688956s","start":"2026-03-14T06:56:46.183727Z","end":"2026-03-14T06:56:47.654416Z","steps":["trace[157700512] 'agreement among raft nodes before linearized reading' (duration: 1.470550517s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.655361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.394639Z","time spent":"1.260674207s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:47.655398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.183703Z","time spent":"1.471680615s","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-14T06:56:47.655547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.494179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-03-14T06:56:47.655605Z","caller":"traceutil/trace.go:171","msg":"trace[98858161] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:505; }","duration":"671.552868ms","start":"2026-03-14T06:56:46.984038Z","end":"2026-03-14T06:56:47.655591Z","steps":["trace[98858161] 'agreement among raft nodes before linearized reading' (duration: 671.40721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.655647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.983995Z","time spent":"671.640387ms","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-03-14T06:56:47.655651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.785613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-03-14T06:56:47.655707Z","caller":"traceutil/trace.go:171","msg":"trace[1860215236] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:505; }","duration":"871.859342ms","start":"2026-03-14T06:56:46.783827Z","end":"2026-03-14T06:56:47.655686Z","steps":["trace[1860215236] 'agreement among raft nodes before linearized reading' (duration: 871.760484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.655667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.769792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24235"} {"level":"warn","ts":"2026-03-14T06:56:47.65575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.783813Z","time spent":"871.926812ms","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-14T06:56:47.655763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.572387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:47.655781Z","caller":"traceutil/trace.go:171","msg":"trace[189380422] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:505; }","duration":"471.96874ms","start":"2026-03-14T06:56:47.183785Z","end":"2026-03-14T06:56:47.655753Z","steps":["trace[189380422] 'agreement among raft nodes before linearized reading' (duration: 471.708983ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:47.655802Z","caller":"traceutil/trace.go:171","msg":"trace[1997423587] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:505; }","duration":"569.628045ms","start":"2026-03-14T06:56:47.086161Z","end":"2026-03-14T06:56:47.655789Z","steps":["trace[1997423587] 'agreement among raft nodes before linearized reading' (duration: 569.570676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.655837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:47.183768Z","time spent":"472.056549ms","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24258,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-03-14T06:56:47.655846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:47.08615Z","time spent":"569.686565ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T06:56:47.655874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.276179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:47.655917Z","caller":"traceutil/trace.go:171","msg":"trace[1733614105] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:505; }","duration":"944.354779ms","start":"2026-03-14T06:56:46.711547Z","end":"2026-03-14T06:56:47.655902Z","steps":["trace[1733614105] 'agreement among raft nodes before linearized reading' (duration: 944.29366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.655968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.711524Z","time spent":"944.419818ms","remote":"127.0.0.1:54396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-03-14T06:56:47.654571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.532303Z","time spent":"1.122257884s","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T06:56:47.655263Z","caller":"traceutil/trace.go:171","msg":"trace[191980228] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:505; }","duration":"148.791884ms","start":"2026-03-14T06:56:47.506459Z","end":"2026-03-14T06:56:47.655251Z","steps":["trace[191980228] 'agreement among raft nodes before linearized reading' (duration: 148.686166ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:47.655276Z","caller":"traceutil/trace.go:171","msg":"trace[963359357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:505; }","duration":"1.664438187s","start":"2026-03-14T06:56:45.990826Z","end":"2026-03-14T06:56:47.655264Z","steps":["trace[963359357] 'agreement among raft nodes before linearized reading' (duration: 1.664364468s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.656268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:45.990806Z","time spent":"1.665446925s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T06:56:47.654675Z","caller":"traceutil/trace.go:171","msg":"trace[609633781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"1.25786972s","start":"2026-03-14T06:56:46.396794Z","end":"2026-03-14T06:56:47.654664Z","steps":["trace[609633781] 'agreement among raft nodes before linearized reading' (duration: 1.257796271s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:47.661277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:46.396774Z","time spent":"1.259950117s","remote":"127.0.0.1:54596","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-03-14T06:56:54.478731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.126324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hhq7w\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-14T06:56:54.478831Z","caller":"traceutil/trace.go:171","msg":"trace[1434790928] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hhq7w; range_end:; response_count:1; response_revision:573; }","duration":"296.277243ms","start":"2026-03-14T06:56:54.182533Z","end":"2026-03-14T06:56:54.478811Z","steps":["trace[1434790928] 'range keys from in-memory index tree' (duration: 295.999185ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:54.933248Z","caller":"traceutil/trace.go:171","msg":"trace[656355229] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"328.212444ms","start":"2026-03-14T06:56:54.605011Z","end":"2026-03-14T06:56:54.933223Z","steps":["trace[656355229] 'read index received' (duration: 328.026925ms)","trace[656355229] 'applied index is now lower than readState.Index' (duration: 184.639µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:56:54.93342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.308264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:54.933372Z","caller":"traceutil/trace.go:171","msg":"trace[904131187] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"439.993917ms","start":"2026-03-14T06:56:54.493343Z","end":"2026-03-14T06:56:54.933337Z","steps":["trace[904131187] 'process raft request' (duration: 439.746189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.933437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.397063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:54.933506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.110839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:54.933546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.25897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:54.933512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.89246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:54.93359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.387266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:54.933588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.318873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:54.933605Z","caller":"traceutil/trace.go:171","msg":"trace[9865074] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:574; }","duration":"314.451786ms","start":"2026-03-14T06:56:54.619149Z","end":"2026-03-14T06:56:54.933601Z","steps":["trace[9865074] 'agreement among raft nodes before linearized reading' (duration: 314.427996ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:54.933611Z","caller":"traceutil/trace.go:171","msg":"trace[542979687] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:574; }","duration":"297.05089ms","start":"2026-03-14T06:56:54.636541Z","end":"2026-03-14T06:56:54.933592Z","steps":["trace[542979687] 'agreement among raft nodes before linearized reading' (duration: 296.870321ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:54.93351Z","caller":"traceutil/trace.go:171","msg":"trace[2015302628] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:574; }","duration":"328.500922ms","start":"2026-03-14T06:56:54.604992Z","end":"2026-03-14T06:56:54.933492Z","steps":["trace[2015302628] 'agreement among raft nodes before linearized reading' (duration: 328.363383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.933661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.398305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:54.933676Z","caller":"traceutil/trace.go:171","msg":"trace[909418586] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:574; }","duration":"314.438905ms","start":"2026-03-14T06:56:54.619233Z","end":"2026-03-14T06:56:54.933672Z","steps":["trace[909418586] 'agreement among raft nodes before linearized reading' (duration: 314.414365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.933695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:54.619216Z","time spent":"314.475775ms","remote":"127.0.0.1:38538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T06:56:54.933686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.959175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T06:56:54.933696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:54.604978Z","time spent":"328.692251ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-03-14T06:56:54.933668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.636171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:54.933741Z","caller":"traceutil/trace.go:171","msg":"trace[245004481] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:574; }","duration":"328.026005ms","start":"2026-03-14T06:56:54.605704Z","end":"2026-03-14T06:56:54.93373Z","steps":["trace[245004481] 'agreement among raft nodes before linearized reading' (duration: 327.941305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.933713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:54.493321Z","time spent":"440.174116ms","remote":"127.0.0.1:54492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T06:56:54.933768Z","caller":"traceutil/trace.go:171","msg":"trace[2141502097] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:574; }","duration":"296.750331ms","start":"2026-03-14T06:56:54.636996Z","end":"2026-03-14T06:56:54.933746Z","steps":["trace[2141502097] 'agreement among raft nodes before linearized reading' (duration: 296.573732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.933791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:54.605701Z","time spent":"328.078364ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-03-14T06:56:54.933546Z","caller":"traceutil/trace.go:171","msg":"trace[527695753] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:574; }","duration":"297.164749ms","start":"2026-03-14T06:56:54.636372Z","end":"2026-03-14T06:56:54.933537Z","steps":["trace[527695753] 'agreement among raft nodes before linearized reading' (duration: 297.110769ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:54.933618Z","caller":"traceutil/trace.go:171","msg":"trace[1492824434] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:574; }","duration":"313.359503ms","start":"2026-03-14T06:56:54.620249Z","end":"2026-03-14T06:56:54.933609Z","steps":["trace[1492824434] 'agreement among raft nodes before linearized reading' (duration: 313.320633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.933929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:54.620243Z","time spent":"313.677361ms","remote":"127.0.0.1:38538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-03-14T06:56:54.933953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.319845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:54.933562Z","caller":"traceutil/trace.go:171","msg":"trace[748230996] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:574; }","duration":"285.29389ms","start":"2026-03-14T06:56:54.648264Z","end":"2026-03-14T06:56:54.933558Z","steps":["trace[748230996] 'agreement among raft nodes before linearized reading' (duration: 285.2674ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:54.933971Z","caller":"traceutil/trace.go:171","msg":"trace[1987381922] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:574; }","duration":"284.363765ms","start":"2026-03-14T06:56:54.649602Z","end":"2026-03-14T06:56:54.933966Z","steps":["trace[1987381922] 'agreement among raft nodes before linearized reading' (duration: 284.333365ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.93363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:54.619138Z","time spent":"314.485625ms","remote":"127.0.0.1:38538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-03-14T06:56:54.93346Z","caller":"traceutil/trace.go:171","msg":"trace[2045611576] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:574; }","duration":"328.376954ms","start":"2026-03-14T06:56:54.605072Z","end":"2026-03-14T06:56:54.933449Z","steps":["trace[2045611576] 'agreement among raft nodes before linearized reading' (duration: 328.291274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:54.93408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:54.605069Z","time spent":"329.00089ms","remote":"127.0.0.1:38522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-03-14T06:56:54.933944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.523768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:54.934237Z","caller":"traceutil/trace.go:171","msg":"trace[1161075483] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:574; }","duration":"285.891996ms","start":"2026-03-14T06:56:54.648318Z","end":"2026-03-14T06:56:54.93421Z","steps":["trace[1161075483] 'agreement among raft nodes before linearized reading' (duration: 285.21205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:56.219726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764022604190422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T06:56:56.719907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764022604190422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T06:56:57.220248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764022604190422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T06:56:57.286726Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.573915527s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T06:56:57.287256Z","caller":"traceutil/trace.go:171","msg":"trace[781574630] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"1.568494767s","start":"2026-03-14T06:56:55.71874Z","end":"2026-03-14T06:56:57.287235Z","steps":["trace[781574630] 'read index received' (duration: 1.568293928s)","trace[781574630] 'applied index is now lower than readState.Index' (duration: 200.089µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:56:57.287357Z","caller":"traceutil/trace.go:171","msg":"trace[468515791] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.574627534s","start":"2026-03-14T06:56:55.712698Z","end":"2026-03-14T06:56:57.287326Z","steps":["trace[468515791] 'process raft request' (duration: 1.574403245s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:57.287514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:55.712678Z","time spent":"1.574755144s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:57.287526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535193263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-14T06:56:57.287574Z","caller":"traceutil/trace.go:171","msg":"trace[813773688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:575; }","duration":"1.535296902s","start":"2026-03-14T06:56:55.752266Z","end":"2026-03-14T06:56:57.287563Z","steps":["trace[813773688] 'agreement among raft nodes before linearized reading' (duration: 1.535109293s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:57.287613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:55.752249Z","time spent":"1.535353142s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T06:56:57.287598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.564558288s","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-03-14T06:56:57.287659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546290234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T06:56:57.287756Z","caller":"traceutil/trace.go:171","msg":"trace[1244102195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"1.546424924s","start":"2026-03-14T06:56:55.741312Z","end":"2026-03-14T06:56:57.287737Z","steps":["trace[1244102195] 'agreement among raft nodes before linearized reading' (duration: 1.546141515s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:57.287787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569048135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-14T06:56:57.287808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:55.7413Z","time spent":"1.546498323s","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T06:56:57.287817Z","caller":"traceutil/trace.go:171","msg":"trace[514941621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"1.569103904s","start":"2026-03-14T06:56:55.718703Z","end":"2026-03-14T06:56:57.287807Z","steps":["trace[514941621] 'agreement among raft nodes before linearized reading' (duration: 1.568616626s)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:57.287684Z","caller":"traceutil/trace.go:171","msg":"trace[85203711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"1.564698057s","start":"2026-03-14T06:56:55.722963Z","end":"2026-03-14T06:56:57.287661Z","steps":["trace[85203711] 'agreement among raft nodes before linearized reading' (duration: 1.564408928s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:57.287846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:55.718689Z","time spent":"1.569150663s","remote":"127.0.0.1:54596","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-03-14T06:56:57.287898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:55.722952Z","time spent":"1.564926226s","remote":"127.0.0.1:54596","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-03-14T06:56:57.287598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201477742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:57.287955Z","caller":"traceutil/trace.go:171","msg":"trace[835475342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"1.201904581s","start":"2026-03-14T06:56:56.086038Z","end":"2026-03-14T06:56:57.287942Z","steps":["trace[835475342] 'agreement among raft nodes before linearized reading' (duration: 1.201453573s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:57.288042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:56.086015Z","time spent":"1.20201357s","remote":"127.0.0.1:54312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T06:56:57.445981Z","caller":"traceutil/trace.go:171","msg":"trace[250936714] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"154.414209ms","start":"2026-03-14T06:56:57.291516Z","end":"2026-03-14T06:56:57.44593Z","steps":["trace[250936714] 'process raft request' (duration: 114.047628ms)","trace[250936714] 'compare' (duration: 40.140862ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:56:57.455201Z","caller":"traceutil/trace.go:171","msg":"trace[1603806228] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:594; }","duration":"157.977203ms","start":"2026-03-14T06:56:57.297201Z","end":"2026-03-14T06:56:57.455179Z","steps":["trace[1603806228] 'read index received' (duration: 108.434689ms)","trace[1603806228] 'applied index is now lower than readState.Index' (duration: 49.541484ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:56:57.455346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.131942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:57.455395Z","caller":"traceutil/trace.go:171","msg":"trace[1274714121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"158.221052ms","start":"2026-03-14T06:56:57.297164Z","end":"2026-03-14T06:56:57.455385Z","steps":["trace[1274714121] 'agreement among raft nodes before linearized reading' (duration: 158.134372ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:57.455411Z","caller":"traceutil/trace.go:171","msg":"trace[1671379045] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"161.673349ms","start":"2026-03-14T06:56:57.293714Z","end":"2026-03-14T06:56:57.455387Z","steps":["trace[1671379045] 'process raft request' (duration: 161.35886ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:57.455472Z","caller":"traceutil/trace.go:171","msg":"trace[1553945291] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"162.643035ms","start":"2026-03-14T06:56:57.292783Z","end":"2026-03-14T06:56:57.455426Z","steps":["trace[1553945291] 'process raft request' (duration: 162.157347ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:57.455491Z","caller":"traceutil/trace.go:171","msg":"trace[1272188737] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"160.611983ms","start":"2026-03-14T06:56:57.294848Z","end":"2026-03-14T06:56:57.45546Z","steps":["trace[1272188737] 'process raft request' (duration: 160.266984ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:56:58.913218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.857063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T06:56:58.913323Z","caller":"traceutil/trace.go:171","msg":"trace[122009180] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"973.683613ms","start":"2026-03-14T06:56:57.939621Z","end":"2026-03-14T06:56:58.913304Z","steps":["trace[122009180] 'process raft request' (duration: 640.6553ms)","trace[122009180] 'compare' (duration: 332.601724ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T06:56:58.913379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T06:56:57.939601Z","time spent":"973.750613ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T06:56:59.214879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.596031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T06:56:59.214943Z","caller":"traceutil/trace.go:171","msg":"trace[920821603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"129.703951ms","start":"2026-03-14T06:56:59.085225Z","end":"2026-03-14T06:56:59.214929Z","steps":["trace[920821603] 'range keys from in-memory index tree' (duration: 129.535031ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:56:59.485211Z","caller":"traceutil/trace.go:171","msg":"trace[1786663149] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"187.604795ms","start":"2026-03-14T06:56:59.297573Z","end":"2026-03-14T06:56:59.485178Z","steps":["trace[1786663149] 'process raft request' (duration: 187.372705ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:57:08.569687Z","caller":"traceutil/trace.go:171","msg":"trace[394241027] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"180.666378ms","start":"2026-03-14T06:57:08.388994Z","end":"2026-03-14T06:57:08.569661Z","steps":["trace[394241027] 'process raft request' (duration: 180.483517ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:57:45.912383Z","caller":"traceutil/trace.go:171","msg":"trace[326592978] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"106.502617ms","start":"2026-03-14T06:57:45.805848Z","end":"2026-03-14T06:57:45.91235Z","steps":["trace[326592978] 'process raft request' (duration: 38.823806ms)","trace[326592978] 'compare' (duration: 67.556909ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:57:45.99765Z","caller":"traceutil/trace.go:171","msg":"trace[1237907449] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"191.499018ms","start":"2026-03-14T06:57:45.806124Z","end":"2026-03-14T06:57:45.997623Z","steps":["trace[1237907449] 'process raft request' (duration: 191.341535ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:57:45.997808Z","caller":"traceutil/trace.go:171","msg":"trace[14023879] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"190.831546ms","start":"2026-03-14T06:57:45.806942Z","end":"2026-03-14T06:57:45.997774Z","steps":["trace[14023879] 'process raft request' (duration: 190.640263ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T06:58:20.044467Z","caller":"traceutil/trace.go:171","msg":"trace[2004973771] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"103.934583ms","start":"2026-03-14T06:58:19.940509Z","end":"2026-03-14T06:58:20.044443Z","steps":["trace[2004973771] 'read index received' (duration: 103.848981ms)","trace[2004973771] 'applied index is now lower than readState.Index' (duration: 84.822µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T06:58:20.044514Z","caller":"traceutil/trace.go:171","msg":"trace[1609125501] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"146.64089ms","start":"2026-03-14T06:58:19.897854Z","end":"2026-03-14T06:58:20.044495Z","steps":["trace[1609125501] 'process raft request' (duration: 146.484496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T06:58:20.044671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.111167ms","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-03-14T06:58:20.04472Z","caller":"traceutil/trace.go:171","msg":"trace[618884481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"104.224071ms","start":"2026-03-14T06:58:19.940482Z","end":"2026-03-14T06:58:20.044706Z","steps":["trace[618884481] 'agreement among raft nodes before linearized reading' (duration: 104.060126ms)"],"step_count":1}