{"level":"warn","ts":"2026-03-13T23:01:01.293416Z","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-13T23:01:01.293486Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-13T23:01:01.29354Z","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-13T23:01:01.293549Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-03-13T23:01:01.293563Z","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-13T23:01:01.294165Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-03-13T23:01:01.294235Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-13T23:01:01.298748Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.341262ms"} {"level":"info","ts":"2026-03-13T23:01:01.305072Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-03-13T23:01:01.305136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T23:01:01.305162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-03-13T23:01:01.305172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T23:01:01.305184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-03-13T23:01:01.305222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-03-13T23:01:01.314549Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T23:01:01.317161Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T23:01:01.321636Z","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-13T23:01:01.324606Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T23:01:01.324964Z","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-13T23:01:01.325068Z","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-13T23:01:01.325083Z","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-13T23:01:01.325343Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T23:01:01.326336Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-03-13T23:01:01.327277Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-03-13T23:01:01.331742Z","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-13T23:01:01.331895Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-03-13T23:01:01.331937Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-03-13T23:01:01.332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T23:01:01.332046Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T23:01:02.306465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T23:01:02.306568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T23:01:02.306612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-03-13T23:01:02.306638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-03-13T23:01:02.30665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-03-13T23:01:02.306669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-03-13T23:01:02.306692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-03-13T23:01:02.307894Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:02.30886Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T23:01:02.308898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:01:02.308937Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:01:02.309362Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:02.309491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T23:01:02.309525Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T23:01:02.310376Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:02.310437Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:01:02.3127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-03-13T23:01:02.312733Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T23:01:26.231877Z","caller":"traceutil/trace.go:171","msg":"trace[1557687058] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"208.145774ms","start":"2026-03-13T23:01:26.023707Z","end":"2026-03-13T23:01:26.231853Z","steps":["trace[1557687058] 'process raft request' (duration: 207.995083ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:26.591757Z","caller":"traceutil/trace.go:171","msg":"trace[1606710035] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"347.812038ms","start":"2026-03-13T23:01:26.243927Z","end":"2026-03-13T23:01:26.59174Z","steps":["trace[1606710035] 'process raft request' (duration: 338.705241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:26.592193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:26.243912Z","time spent":"347.940538ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:01:30.520425Z","caller":"traceutil/trace.go:171","msg":"trace[1464140804] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"272.155053ms","start":"2026-03-13T23:01:30.248243Z","end":"2026-03-13T23:01:30.520398Z","steps":["trace[1464140804] 'process raft request' (duration: 271.961352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:36.933933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.844552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-13T23:01:36.933955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.871574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-13T23:01:36.934079Z","caller":"traceutil/trace.go:171","msg":"trace[1775428037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"112.022842ms","start":"2026-03-13T23:01:36.822026Z","end":"2026-03-13T23:01:36.934049Z","steps":["trace[1775428037] 'range keys from in-memory index tree' (duration: 111.741367ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:36.934087Z","caller":"traceutil/trace.go:171","msg":"trace[33972750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"112.043953ms","start":"2026-03-13T23:01:36.822024Z","end":"2026-03-13T23:01:36.934068Z","steps":["trace[33972750] 'range keys from in-memory index tree' (duration: 111.761848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:36.933974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.352417ms","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-13T23:01:36.93414Z","caller":"traceutil/trace.go:171","msg":"trace[438758974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:542; }","duration":"305.528966ms","start":"2026-03-13T23:01:36.628599Z","end":"2026-03-13T23:01:36.934128Z","steps":["trace[438758974] 'range keys from in-memory index tree' (duration: 305.246482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:36.934168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:36.628532Z","time spent":"305.629452ms","remote":"127.0.0.1:51774","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-03-13T23:01:38.845292Z","caller":"traceutil/trace.go:171","msg":"trace[1230276466] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"236.052105ms","start":"2026-03-13T23:01:38.609205Z","end":"2026-03-13T23:01:38.845257Z","steps":["trace[1230276466] 'read index received' (duration: 235.795483ms)","trace[1230276466] 'applied index is now lower than readState.Index' (duration: 255.412µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:01:38.845473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.230024ms","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-03-13T23:01:38.845521Z","caller":"traceutil/trace.go:171","msg":"trace[1463468476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"236.36057ms","start":"2026-03-13T23:01:38.609145Z","end":"2026-03-13T23:01:38.845505Z","steps":["trace[1463468476] 'agreement among raft nodes before linearized reading' (duration: 236.233404ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:38.84561Z","caller":"traceutil/trace.go:171","msg":"trace[1056088160] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"486.501408ms","start":"2026-03-13T23:01:38.359077Z","end":"2026-03-13T23:01:38.845578Z","steps":["trace[1056088160] 'process raft request' (duration: 486.006964ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:38.845756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:38.359042Z","time spent":"486.636775ms","remote":"127.0.0.1:51700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:01:41.011021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:40.639674Z","time spent":"371.339341ms","remote":"127.0.0.1:59406","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-13T23:01:41.01158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.643917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:01:41.011797Z","caller":"traceutil/trace.go:171","msg":"trace[1364497479] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"2.022050109s","start":"2026-03-13T23:01:38.989731Z","end":"2026-03-13T23:01:41.011782Z","steps":["trace[1364497479] 'process raft request' (duration: 1.173952431s)","trace[1364497479] 'compare' (duration: 847.324862ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:01:41.01187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:38.989703Z","time spent":"2.022142573s","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:01:41.061083Z","caller":"traceutil/trace.go:171","msg":"trace[246961789] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"1.193092064s","start":"2026-03-13T23:01:39.867982Z","end":"2026-03-13T23:01:41.061075Z","steps":["trace[246961789] 'read index received' (duration: 295.67212ms)","trace[246961789] 'applied index is now lower than readState.Index' (duration: 897.419184ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:01:41.061274Z","caller":"traceutil/trace.go:171","msg":"trace[2070479518] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"2.071388585s","start":"2026-03-13T23:01:38.989879Z","end":"2026-03-13T23:01:41.061268Z","steps":["trace[2070479518] 'process raft request' (duration: 2.071110332s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:41.06133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:38.989862Z","time spent":"2.071448087s","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:01:41.061433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193462001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:01:41.061492Z","caller":"traceutil/trace.go:171","msg":"trace[822304885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"1.193571086s","start":"2026-03-13T23:01:39.867915Z","end":"2026-03-13T23:01:41.061486Z","steps":["trace[822304885] 'agreement among raft nodes before linearized reading' (duration: 1.193495733s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:41.061523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:39.867887Z","time spent":"1.193629019s","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:01:41.066381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.497745ms","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-03-13T23:01:41.066445Z","caller":"traceutil/trace.go:171","msg":"trace[433196849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:554; }","duration":"150.60156ms","start":"2026-03-13T23:01:40.915829Z","end":"2026-03-13T23:01:41.066431Z","steps":["trace[433196849] 'agreement among raft nodes before linearized reading' (duration: 150.496275ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:41.495213Z","caller":"traceutil/trace.go:171","msg":"trace[1981484031] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"126.831344ms","start":"2026-03-13T23:01:41.368357Z","end":"2026-03-13T23:01:41.495188Z","steps":["trace[1981484031] 'process raft request' (duration: 126.687447ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:42.9745Z","caller":"traceutil/trace.go:171","msg":"trace[1251847287] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"351.742818ms","start":"2026-03-13T23:01:42.62273Z","end":"2026-03-13T23:01:42.974473Z","steps":["trace[1251847287] 'process raft request' (duration: 351.599992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:01:42.974672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:01:42.622702Z","time spent":"351.893216ms","remote":"127.0.0.1:51546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:01:42.978056Z","caller":"traceutil/trace.go:171","msg":"trace[244771672] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"114.140107ms","start":"2026-03-13T23:01:42.863882Z","end":"2026-03-13T23:01:42.978023Z","steps":["trace[244771672] 'read index received' (duration: 110.496637ms)","trace[244771672] 'applied index is now lower than readState.Index' (duration: 3.64256ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:01:42.97839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.489774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:01:42.978428Z","caller":"traceutil/trace.go:171","msg":"trace[86974474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"114.571278ms","start":"2026-03-13T23:01:42.863847Z","end":"2026-03-13T23:01:42.978418Z","steps":["trace[86974474] 'agreement among raft nodes before linearized reading' (duration: 114.460693ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:01:42.978648Z","caller":"traceutil/trace.go:171","msg":"trace[285127798] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"157.178731ms","start":"2026-03-13T23:01:42.82145Z","end":"2026-03-13T23:01:42.978629Z","steps":["trace[285127798] 'process raft request' (duration: 156.475978ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:05.282309Z","caller":"traceutil/trace.go:171","msg":"trace[820717165] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"116.869986ms","start":"2026-03-13T23:02:05.165399Z","end":"2026-03-13T23:02:05.282269Z","steps":["trace[820717165] 'read index received' (duration: 116.494852ms)","trace[820717165] 'applied index is now lower than readState.Index' (duration: 373.514µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:02:05.282481Z","caller":"traceutil/trace.go:171","msg":"trace[262875943] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"155.221017ms","start":"2026-03-13T23:02:05.127247Z","end":"2026-03-13T23:02:05.282468Z","steps":["trace[262875943] 'process raft request' (duration: 154.816023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:05.282749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.14659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-13T23:02:05.28284Z","caller":"traceutil/trace.go:171","msg":"trace[1819677978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"102.373228ms","start":"2026-03-13T23:02:05.180444Z","end":"2026-03-13T23:02:05.282818Z","steps":["trace[1819677978] 'agreement among raft nodes before linearized reading' (duration: 102.13199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:05.283104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.709106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:02:05.283159Z","caller":"traceutil/trace.go:171","msg":"trace[1241584311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"117.784439ms","start":"2026-03-13T23:02:05.165357Z","end":"2026-03-13T23:02:05.283142Z","steps":["trace[1241584311] 'agreement among raft nodes before linearized reading' (duration: 117.656905ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:05.283354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.754671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-13T23:02:05.283382Z","caller":"traceutil/trace.go:171","msg":"trace[754520840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"102.822774ms","start":"2026-03-13T23:02:05.18055Z","end":"2026-03-13T23:02:05.283373Z","steps":["trace[754520840] 'agreement among raft nodes before linearized reading' (duration: 102.741181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:07.099608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.100197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-03-13T23:02:07.099694Z","caller":"traceutil/trace.go:171","msg":"trace[1176322774] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:781; }","duration":"135.256073ms","start":"2026-03-13T23:02:06.964419Z","end":"2026-03-13T23:02:07.099675Z","steps":["trace[1176322774] 'range keys from in-memory index tree' (duration: 134.965833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:07.100052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.24214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:02:07.100149Z","caller":"traceutil/trace.go:171","msg":"trace[1421891634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"236.631093ms","start":"2026-03-13T23:02:06.863486Z","end":"2026-03-13T23:02:07.100117Z","steps":["trace[1421891634] 'range keys from in-memory index tree' (duration: 236.108686ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:09.805282Z","caller":"traceutil/trace.go:171","msg":"trace[1120567227] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"487.606132ms","start":"2026-03-13T23:02:09.317652Z","end":"2026-03-13T23:02:09.805258Z","steps":["trace[1120567227] 'process raft request' (duration: 487.473998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:09.805499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:09.317628Z","time spent":"487.735597ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:10.059024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.365654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:02:10.059131Z","caller":"traceutil/trace.go:171","msg":"trace[1160418147] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"725.201675ms","start":"2026-03-13T23:02:09.333912Z","end":"2026-03-13T23:02:10.059114Z","steps":["trace[1160418147] 'read index received' (duration: 471.482781ms)","trace[1160418147] 'applied index is now lower than readState.Index' (duration: 253.717834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:10.059329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.947894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:02:10.059359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.933314ms","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-13T23:02:10.059407Z","caller":"traceutil/trace.go:171","msg":"trace[1364489048] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"724.998177ms","start":"2026-03-13T23:02:09.334394Z","end":"2026-03-13T23:02:10.059392Z","steps":["trace[1364489048] 'agreement among raft nodes before linearized reading' (duration: 724.835512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:10.059441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:09.334386Z","time spent":"725.047268ms","remote":"127.0.0.1:51774","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-03-13T23:02:10.059452Z","caller":"traceutil/trace.go:171","msg":"trace[1852807727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"195.064759ms","start":"2026-03-13T23:02:09.864322Z","end":"2026-03-13T23:02:10.059387Z","steps":["trace[1852807727] 'agreement among raft nodes before linearized reading' (duration: 194.959335ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:10.059837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.914829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-13T23:02:10.059885Z","caller":"traceutil/trace.go:171","msg":"trace[1153124822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"725.98286ms","start":"2026-03-13T23:02:09.33389Z","end":"2026-03-13T23:02:10.059873Z","steps":["trace[1153124822] 'agreement among raft nodes before linearized reading' (duration: 725.308418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:10.059915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:09.333881Z","time spent":"726.026653ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-13T23:02:10.060243Z","caller":"traceutil/trace.go:171","msg":"trace[910693283] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"739.870926ms","start":"2026-03-13T23:02:09.319263Z","end":"2026-03-13T23:02:10.059134Z","steps":["trace[910693283] 'process raft request' (duration: 550.14389ms)","trace[910693283] 'compare' (duration: 189.259241ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:10.060347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:09.319248Z","time spent":"741.028585ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:02:10.369539Z","caller":"traceutil/trace.go:171","msg":"trace[686386171] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"304.53564ms","start":"2026-03-13T23:02:10.064956Z","end":"2026-03-13T23:02:10.369492Z","steps":["trace[686386171] 'process raft request' (duration: 291.986055ms)","trace[686386171] 'compare' (duration: 11.622113ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:10.369816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.064933Z","time spent":"304.743156ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:02:11.12164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.190617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-13T23:02:11.121748Z","caller":"traceutil/trace.go:171","msg":"trace[2116809795] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"511.018829ms","start":"2026-03-13T23:02:10.61071Z","end":"2026-03-13T23:02:11.121729Z","steps":["trace[2116809795] 'read index received' (duration: 1.736439ms)","trace[2116809795] 'applied index is now lower than readState.Index' (duration: 509.28072ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:02:11.121902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.668425ms","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-13T23:02:11.121932Z","caller":"traceutil/trace.go:171","msg":"trace[1477976495] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:792; }","duration":"402.742498ms","start":"2026-03-13T23:02:10.71918Z","end":"2026-03-13T23:02:11.121923Z","steps":["trace[1477976495] 'agreement among raft nodes before linearized reading' (duration: 402.668025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:11.121951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.524312ms","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-13T23:02:11.121957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.245787ms","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-03-13T23:02:11.12196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.669003ms","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-13T23:02:11.122007Z","caller":"traceutil/trace.go:171","msg":"trace[1721448409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"511.34612ms","start":"2026-03-13T23:02:10.61065Z","end":"2026-03-13T23:02:11.121996Z","steps":["trace[1721448409] 'agreement among raft nodes before linearized reading' (duration: 511.175604ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:11.122003Z","caller":"traceutil/trace.go:171","msg":"trace[1308547607] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:792; }","duration":"392.613085ms","start":"2026-03-13T23:02:10.729375Z","end":"2026-03-13T23:02:11.121988Z","steps":["trace[1308547607] 'agreement among raft nodes before linearized reading' (duration: 392.511801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:11.122022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.773659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:02:11.122032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.610622Z","time spent":"511.404282ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T23:02:11.122041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.729362Z","time spent":"392.669516ms","remote":"127.0.0.1:52286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"info","ts":"2026-03-13T23:02:11.122046Z","caller":"traceutil/trace.go:171","msg":"trace[606247917] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:792; }","duration":"402.80801ms","start":"2026-03-13T23:02:10.719231Z","end":"2026-03-13T23:02:11.122039Z","steps":["trace[606247917] 'agreement among raft nodes before linearized reading' (duration: 402.768758ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:11.122021Z","caller":"traceutil/trace.go:171","msg":"trace[757206141] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:792; }","duration":"391.785586ms","start":"2026-03-13T23:02:10.730219Z","end":"2026-03-13T23:02:11.122005Z","steps":["trace[757206141] 'agreement among raft nodes before linearized reading' (duration: 391.640902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:11.122021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.712776ms","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-13T23:02:11.122075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.719227Z","time spent":"402.840891ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"info","ts":"2026-03-13T23:02:11.122176Z","caller":"traceutil/trace.go:171","msg":"trace[1060604415] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:792; }","duration":"402.850891ms","start":"2026-03-13T23:02:10.719259Z","end":"2026-03-13T23:02:11.12211Z","steps":["trace[1060604415] 'agreement among raft nodes before linearized reading' (duration: 402.649704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:11.122218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.447438ms","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-13T23:02:11.122092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.730195Z","time spent":"391.88513ms","remote":"127.0.0.1:52286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-03-13T23:02:11.122223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.410767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:02:11.122238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.719246Z","time spent":"402.979535ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:02:11.12226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.696283ms","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-13T23:02:11.122257Z","caller":"traceutil/trace.go:171","msg":"trace[1920280305] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:792; }","duration":"376.52243ms","start":"2026-03-13T23:02:10.745723Z","end":"2026-03-13T23:02:11.122245Z","steps":["trace[1920280305] 'agreement among raft nodes before linearized reading' (duration: 376.458558ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:11.122282Z","caller":"traceutil/trace.go:171","msg":"trace[1711621365] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:792; }","duration":"375.738643ms","start":"2026-03-13T23:02:10.746536Z","end":"2026-03-13T23:02:11.122275Z","steps":["trace[1711621365] 'agreement among raft nodes before linearized reading' (duration: 375.704352ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:02:11.122286Z","caller":"traceutil/trace.go:171","msg":"trace[2032606964] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:792; }","duration":"376.49809ms","start":"2026-03-13T23:02:10.745768Z","end":"2026-03-13T23:02:11.122266Z","steps":["trace[2032606964] 'agreement among raft nodes before linearized reading' (duration: 376.355605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:11.1223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.746523Z","time spent":"375.772265ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-03-13T23:02:11.122291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.745702Z","time spent":"376.580863ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-03-13T23:02:11.122317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.883473ms","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-13T23:02:11.122327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.745754Z","time spent":"376.563212ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:02:11.122352Z","caller":"traceutil/trace.go:171","msg":"trace[117816866] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:792; }","duration":"392.919435ms","start":"2026-03-13T23:02:10.729422Z","end":"2026-03-13T23:02:11.122342Z","steps":["trace[117816866] 'agreement among raft nodes before linearized reading' (duration: 392.866223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:11.121972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.719162Z","time spent":"402.798719ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-03-13T23:02:11.122382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:02:10.729412Z","time spent":"392.962057ms","remote":"127.0.0.1:52286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:02:11.951236Z","caller":"traceutil/trace.go:171","msg":"trace[60831305] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"136.091843ms","start":"2026-03-13T23:02:11.815093Z","end":"2026-03-13T23:02:11.951185Z","steps":["trace[60831305] 'process raft request' (duration: 135.746161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:12.222665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.809115ms","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-13T23:02:12.22275Z","caller":"traceutil/trace.go:171","msg":"trace[1598353718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"148.94803ms","start":"2026-03-13T23:02:12.073782Z","end":"2026-03-13T23:02:12.22273Z","steps":["trace[1598353718] 'range keys from in-memory index tree' (duration: 148.625909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:02:12.22308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.356591ms","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-03-13T23:02:12.223103Z","caller":"traceutil/trace.go:171","msg":"trace[1448115816] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"158.432984ms","start":"2026-03-13T23:02:12.064664Z","end":"2026-03-13T23:02:12.223097Z","steps":["trace[1448115816] 'range keys from in-memory index tree' (duration: 158.244897ms)"],"step_count":1}