{"level":"warn","ts":"2026-02-12T22:42:03.960486Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T22:42:03.960682Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-12T22:42:03.960844Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-12T22:42:03.960886Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-12T22:42:03.960929Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T22:42:03.961736Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-02-12T22:42:03.961903Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-12T22:42:03.965998Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.765533ms"} {"level":"info","ts":"2026-02-12T22:42:03.998218Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-02-12T22:42:03.998486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T22:42:03.998572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-02-12T22:42:03.9987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T22:42:03.998731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-02-12T22:42:03.998798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-02-12T22:42:04.011385Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T22:42:04.02284Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T22:42:04.026181Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-12T22:42:04.039309Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T22:42:04.03974Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T22:42:04.039964Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T22:42:04.040043Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T22:42:04.040069Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-12T22:42:04.040703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-02-12T22:42:04.04082Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-12T22:42:04.049258Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-12T22:42:04.049378Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-12T22:42:04.050645Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-12T22:42:04.056086Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T22:42:04.056799Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T22:42:04.600279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T22:42:04.600464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T22:42:04.600501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-02-12T22:42:04.600512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-02-12T22:42:04.600517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-12T22:42:04.600532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-02-12T22:42:04.600538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-12T22:42:04.601814Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:42:04.603172Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T22:42:04.603238Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T22:42:04.603606Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T22:42:04.603881Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:42:04.603984Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:42:04.60401Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:42:04.610255Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-02-12T22:42:04.611281Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T22:42:04.611299Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T22:42:04.618802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T22:42:11.319385Z","caller":"traceutil/trace.go:171","msg":"trace[1957332836] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"388.526855ms","start":"2026-02-12T22:42:10.930839Z","end":"2026-02-12T22:42:11.319366Z","steps":["trace[1957332836] 'process raft request' (duration: 388.402732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:11.319769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:10.930826Z","time spent":"388.626297ms","remote":"127.0.0.1:48768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:11.384209Z","caller":"traceutil/trace.go:171","msg":"trace[1663433143] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"418.560354ms","start":"2026-02-12T22:42:10.965633Z","end":"2026-02-12T22:42:11.384193Z","steps":["trace[1663433143] 'process raft request' (duration: 418.382591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:11.38429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:10.965608Z","time spent":"418.646466ms","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T22:42:11.766147Z","caller":"traceutil/trace.go:171","msg":"trace[1225832406] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"282.655328ms","start":"2026-02-12T22:42:11.483467Z","end":"2026-02-12T22:42:11.766122Z","steps":["trace[1225832406] 'read index received' (duration: 282.196747ms)","trace[1225832406] 'applied index is now lower than readState.Index' (duration: 457.701µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:42:11.766253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.76902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-12T22:42:11.766299Z","caller":"traceutil/trace.go:171","msg":"trace[1787750864] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:277; }","duration":"282.856523ms","start":"2026-02-12T22:42:11.483435Z","end":"2026-02-12T22:42:11.766292Z","steps":["trace[1787750864] 'agreement among raft nodes before linearized reading' (duration: 282.769941ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:11.766448Z","caller":"traceutil/trace.go:171","msg":"trace[6463877] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"371.676562ms","start":"2026-02-12T22:42:11.394753Z","end":"2026-02-12T22:42:11.76643Z","steps":["trace[6463877] 'process raft request' (duration: 370.965366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:11.766576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:11.394737Z","time spent":"371.755773ms","remote":"127.0.0.1:48768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:13.756017Z","caller":"traceutil/trace.go:171","msg":"trace[2102045728] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"271.988103ms","start":"2026-02-12T22:42:13.484005Z","end":"2026-02-12T22:42:13.755994Z","steps":["trace[2102045728] 'read index received' (duration: 271.85279ms)","trace[2102045728] 'applied index is now lower than readState.Index' (duration: 134.463µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:42:13.756169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.145776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-12T22:42:13.756206Z","caller":"traceutil/trace.go:171","msg":"trace[1693879432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:300; }","duration":"272.218327ms","start":"2026-02-12T22:42:13.483978Z","end":"2026-02-12T22:42:13.756197Z","steps":["trace[1693879432] 'agreement among raft nodes before linearized reading' (duration: 272.112485ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:13.756255Z","caller":"traceutil/trace.go:171","msg":"trace[1199570128] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"289.849681ms","start":"2026-02-12T22:42:13.466388Z","end":"2026-02-12T22:42:13.756238Z","steps":["trace[1199570128] 'process raft request' (duration: 289.491653ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:14.128853Z","caller":"traceutil/trace.go:171","msg":"trace[1350701819] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"306.76375ms","start":"2026-02-12T22:42:13.822072Z","end":"2026-02-12T22:42:14.128835Z","steps":["trace[1350701819] 'read index received' (duration: 211.43713ms)","trace[1350701819] 'applied index is now lower than readState.Index' (duration: 95.32602ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:14.129063Z","caller":"traceutil/trace.go:171","msg":"trace[2051727392] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"369.584874ms","start":"2026-02-12T22:42:13.75947Z","end":"2026-02-12T22:42:14.129055Z","steps":["trace[2051727392] 'process raft request' (duration: 274.08164ms)","trace[2051727392] 'compare' (duration: 95.214548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:42:14.129135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:13.759455Z","time spent":"369.631065ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:42:14.129269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.196651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-12T22:42:14.129294Z","caller":"traceutil/trace.go:171","msg":"trace[769520264] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:301; }","duration":"307.257251ms","start":"2026-02-12T22:42:13.822031Z","end":"2026-02-12T22:42:14.129288Z","steps":["trace[769520264] 'agreement among raft nodes before linearized reading' (duration: 307.20655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.129312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:13.822009Z","time spent":"307.299913ms","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-12T22:42:14.129429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.673414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-12T22:42:14.129449Z","caller":"traceutil/trace.go:171","msg":"trace[626483736] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:301; }","duration":"291.721256ms","start":"2026-02-12T22:42:13.837723Z","end":"2026-02-12T22:42:14.129444Z","steps":["trace[626483736] 'agreement among raft nodes before linearized reading' (duration: 291.674685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.129577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.9749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-12T22:42:14.129598Z","caller":"traceutil/trace.go:171","msg":"trace[1463862048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:301; }","duration":"107.017992ms","start":"2026-02-12T22:42:14.022575Z","end":"2026-02-12T22:42:14.129593Z","steps":["trace[1463862048] 'agreement among raft nodes before linearized reading' (duration: 106.982161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.129677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.061469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T22:42:14.129705Z","caller":"traceutil/trace.go:171","msg":"trace[320110044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:301; }","duration":"144.11916ms","start":"2026-02-12T22:42:13.985573Z","end":"2026-02-12T22:42:14.129692Z","steps":["trace[320110044] 'agreement among raft nodes before linearized reading' (duration: 144.086149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.12978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.520725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-12T22:42:14.129801Z","caller":"traceutil/trace.go:171","msg":"trace[803364564] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:301; }","duration":"164.580276ms","start":"2026-02-12T22:42:13.965216Z","end":"2026-02-12T22:42:14.129796Z","steps":["trace[803364564] 'agreement among raft nodes before linearized reading' (duration: 164.547036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.129882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.587801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-12T22:42:14.129903Z","caller":"traceutil/trace.go:171","msg":"trace[246024991] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:301; }","duration":"213.644282ms","start":"2026-02-12T22:42:13.916253Z","end":"2026-02-12T22:42:14.129897Z","steps":["trace[246024991] 'agreement among raft nodes before linearized reading' (duration: 213.612701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.129978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.157839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-12T22:42:14.129999Z","caller":"traceutil/trace.go:171","msg":"trace[1717597182] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:301; }","duration":"251.17792ms","start":"2026-02-12T22:42:13.878816Z","end":"2026-02-12T22:42:14.129994Z","steps":["trace[1717597182] 'agreement among raft nodes before linearized reading' (duration: 251.146599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.130097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.749446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-12T22:42:14.130117Z","caller":"traceutil/trace.go:171","msg":"trace[1646948328] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:301; }","duration":"252.797577ms","start":"2026-02-12T22:42:13.877314Z","end":"2026-02-12T22:42:14.130112Z","steps":["trace[1646948328] 'agreement among raft nodes before linearized reading' (duration: 252.756256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.130189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.394112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-12T22:42:14.130208Z","caller":"traceutil/trace.go:171","msg":"trace[525069079] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:301; }","duration":"258.432732ms","start":"2026-02-12T22:42:13.871771Z","end":"2026-02-12T22:42:14.130204Z","steps":["trace[525069079] 'agreement among raft nodes before linearized reading' (duration: 258.401322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.130309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.306546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-12T22:42:14.130332Z","caller":"traceutil/trace.go:171","msg":"trace[2108692394] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:301; }","duration":"265.343517ms","start":"2026-02-12T22:42:13.864983Z","end":"2026-02-12T22:42:14.130327Z","steps":["trace[2108692394] 'agreement among raft nodes before linearized reading' (duration: 265.303796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.130849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.295152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-12T22:42:14.13089Z","caller":"traceutil/trace.go:171","msg":"trace[655558544] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:301; }","duration":"268.355214ms","start":"2026-02-12T22:42:13.862525Z","end":"2026-02-12T22:42:14.130881Z","steps":["trace[655558544] 'agreement among raft nodes before linearized reading' (duration: 268.293143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.130976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.036294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-12T22:42:14.131146Z","caller":"traceutil/trace.go:171","msg":"trace[467193809] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:301; }","duration":"284.235929ms","start":"2026-02-12T22:42:13.846901Z","end":"2026-02-12T22:42:14.131137Z","steps":["trace[467193809] 'agreement among raft nodes before linearized reading' (duration: 284.058935ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:14.560527Z","caller":"traceutil/trace.go:171","msg":"trace[389479706] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"162.078323ms","start":"2026-02-12T22:42:14.398431Z","end":"2026-02-12T22:42:14.560509Z","steps":["trace[389479706] 'process raft request' (duration: 147.009166ms)","trace[389479706] 'compare' (duration: 14.914373ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:14.560865Z","caller":"traceutil/trace.go:171","msg":"trace[1905421439] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"159.892363ms","start":"2026-02-12T22:42:14.400965Z","end":"2026-02-12T22:42:14.560858Z","steps":["trace[1905421439] 'process raft request' (duration: 159.502155ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:14.561234Z","caller":"traceutil/trace.go:171","msg":"trace[1426994370] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:323; }","duration":"145.972663ms","start":"2026-02-12T22:42:14.415253Z","end":"2026-02-12T22:42:14.561226Z","steps":["trace[1426994370] 'read index received' (duration: 13.594685ms)","trace[1426994370] 'applied index is now lower than readState.Index' (duration: 132.377358ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:14.561283Z","caller":"traceutil/trace.go:171","msg":"trace[1890110247] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"159.649038ms","start":"2026-02-12T22:42:14.401629Z","end":"2026-02-12T22:42:14.561278Z","steps":["trace[1890110247] 'process raft request' (duration: 159.424043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:14.561439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.153757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-12T22:42:14.561483Z","caller":"traceutil/trace.go:171","msg":"trace[1144583216] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:319; }","duration":"146.248049ms","start":"2026-02-12T22:42:14.415228Z","end":"2026-02-12T22:42:14.561476Z","steps":["trace[1144583216] 'agreement among raft nodes before linearized reading' (duration: 146.146176ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:14.561231Z","caller":"traceutil/trace.go:171","msg":"trace[1816477189] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"160.144998ms","start":"2026-02-12T22:42:14.40107Z","end":"2026-02-12T22:42:14.561215Z","steps":["trace[1816477189] 'process raft request' (duration: 159.444343ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:14.944209Z","caller":"traceutil/trace.go:171","msg":"trace[1399280436] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"152.683262ms","start":"2026-02-12T22:42:14.7915Z","end":"2026-02-12T22:42:14.944183Z","steps":["trace[1399280436] 'process raft request' (duration: 132.162054ms)","trace[1399280436] 'compare' (duration: 20.408815ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:15.03015Z","caller":"traceutil/trace.go:171","msg":"trace[1138446477] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"208.524201ms","start":"2026-02-12T22:42:14.82161Z","end":"2026-02-12T22:42:15.030134Z","steps":["trace[1138446477] 'process raft request' (duration: 208.382268ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:15.030196Z","caller":"traceutil/trace.go:171","msg":"trace[872345540] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:346; }","duration":"144.07905ms","start":"2026-02-12T22:42:14.886102Z","end":"2026-02-12T22:42:15.030181Z","steps":["trace[872345540] 'read index received' (duration: 37.56832ms)","trace[872345540] 'applied index is now lower than readState.Index' (duration: 106.51003ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:42:15.030289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.177253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-12T22:42:15.030317Z","caller":"traceutil/trace.go:171","msg":"trace[349382374] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:343; }","duration":"144.342256ms","start":"2026-02-12T22:42:14.885967Z","end":"2026-02-12T22:42:15.030309Z","steps":["trace[349382374] 'agreement among raft nodes before linearized reading' (duration: 144.264305ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:15.030448Z","caller":"traceutil/trace.go:171","msg":"trace[1144677654] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"208.747366ms","start":"2026-02-12T22:42:14.821696Z","end":"2026-02-12T22:42:15.030444Z","steps":["trace[1144677654] 'process raft request' (duration: 208.378718ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:15.030509Z","caller":"traceutil/trace.go:171","msg":"trace[425937932] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"206.554736ms","start":"2026-02-12T22:42:14.823941Z","end":"2026-02-12T22:42:15.030495Z","steps":["trace[425937932] 'process raft request' (duration: 206.166168ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:15.030572Z","caller":"traceutil/trace.go:171","msg":"trace[1550229152] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"200.449201ms","start":"2026-02-12T22:42:14.830118Z","end":"2026-02-12T22:42:15.030567Z","steps":["trace[1550229152] 'process raft request' (duration: 200.025302ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:15.198837Z","caller":"traceutil/trace.go:171","msg":"trace[4877048] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"160.426088ms","start":"2026-02-12T22:42:15.038391Z","end":"2026-02-12T22:42:15.198817Z","steps":["trace[4877048] 'process raft request' (duration: 160.374087ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:15.199383Z","caller":"traceutil/trace.go:171","msg":"trace[2142236872] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"160.97097ms","start":"2026-02-12T22:42:15.038387Z","end":"2026-02-12T22:42:15.199358Z","steps":["trace[2142236872] 'process raft request' (duration: 131.178934ms)","trace[2142236872] 'compare' (duration: 28.903327ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:15.231583Z","caller":"traceutil/trace.go:171","msg":"trace[1978937700] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"192.905815ms","start":"2026-02-12T22:42:15.038657Z","end":"2026-02-12T22:42:15.231563Z","steps":["trace[1978937700] 'process raft request' (duration: 192.819383ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:15.231632Z","caller":"traceutil/trace.go:171","msg":"trace[122417237] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"193.152921ms","start":"2026-02-12T22:42:15.038454Z","end":"2026-02-12T22:42:15.231607Z","steps":["trace[122417237] 'process raft request' (duration: 192.928026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:17.73936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870727054844458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T22:42:18.240293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870727054844458,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T22:42:18.652113Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.714027948s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T22:42:18.652364Z","caller":"traceutil/trace.go:171","msg":"trace[1210942132] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"1.41371263s","start":"2026-02-12T22:42:17.238637Z","end":"2026-02-12T22:42:18.652349Z","steps":["trace[1210942132] 'read index received' (duration: 1.413582797s)","trace[1210942132] 'applied index is now lower than readState.Index' (duration: 129.243µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:42:18.652587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.39501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T22:42:18.652611Z","caller":"traceutil/trace.go:171","msg":"trace[324040050] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:363; }","duration":"922.454212ms","start":"2026-02-12T22:42:17.730151Z","end":"2026-02-12T22:42:18.652605Z","steps":["trace[324040050] 'agreement among raft nodes before linearized reading' (duration: 922.40069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:18.652627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:17.730138Z","time spent":"922.485542ms","remote":"127.0.0.1:48612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-02-12T22:42:18.652818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.361327ms","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-02-12T22:42:18.652846Z","caller":"traceutil/trace.go:171","msg":"trace[350901506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:363; }","duration":"203.970644ms","start":"2026-02-12T22:42:18.448871Z","end":"2026-02-12T22:42:18.652842Z","steps":["trace[350901506] 'agreement among raft nodes before linearized reading' (duration: 203.931903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:18.652903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.460979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:18.652921Z","caller":"traceutil/trace.go:171","msg":"trace[1328145527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:363; }","duration":"412.49235ms","start":"2026-02-12T22:42:18.240425Z","end":"2026-02-12T22:42:18.652917Z","steps":["trace[1328145527] 'agreement among raft nodes before linearized reading' (duration: 412.468759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:18.652932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.240416Z","time spent":"412.513441ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:42:18.652996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.640125ms","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-02-12T22:42:18.653012Z","caller":"traceutil/trace.go:171","msg":"trace[416361506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:363; }","duration":"487.677557ms","start":"2026-02-12T22:42:18.165331Z","end":"2026-02-12T22:42:18.653008Z","steps":["trace[416361506] 'agreement among raft nodes before linearized reading' (duration: 487.652096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:18.653023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.16532Z","time spent":"487.700637ms","remote":"127.0.0.1:48862","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-02-12T22:42:18.653082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.860677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-12T22:42:18.653098Z","caller":"traceutil/trace.go:171","msg":"trace[1552854009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:363; }","duration":"498.907848ms","start":"2026-02-12T22:42:18.154187Z","end":"2026-02-12T22:42:18.653095Z","steps":["trace[1552854009] 'agreement among raft nodes before linearized reading' (duration: 498.882728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:18.65311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.154169Z","time spent":"498.938379ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T22:42:18.652601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.413922395s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:18.653193Z","caller":"traceutil/trace.go:171","msg":"trace[511174810] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:363; }","duration":"1.414558209s","start":"2026-02-12T22:42:17.238629Z","end":"2026-02-12T22:42:18.653187Z","steps":["trace[511174810] 'agreement among raft nodes before linearized reading' (duration: 1.413883484s)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:18.65319Z","caller":"traceutil/trace.go:171","msg":"trace[336044901] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"1.715138092s","start":"2026-02-12T22:42:16.938014Z","end":"2026-02-12T22:42:18.653152Z","steps":["trace[336044901] 'process raft request' (duration: 1.714244062s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:18.653304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:16.938003Z","time spent":"1.715236984s","remote":"127.0.0.1:48768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:42:20.308771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645819847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-12T22:42:20.308855Z","caller":"traceutil/trace.go:171","msg":"trace[1301512103] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:363; }","duration":"1.64594134s","start":"2026-02-12T22:42:18.662891Z","end":"2026-02-12T22:42:20.308832Z","steps":["trace[1301512103] 'range keys from in-memory index tree' (duration: 1.645731415s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:20.308917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.662877Z","time spent":"1.646022692s","remote":"127.0.0.1:48768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-12T22:42:20.309437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.341697218s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-12T22:42:20.309509Z","caller":"traceutil/trace.go:171","msg":"trace[1026638636] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"1.645981411s","start":"2026-02-12T22:42:18.663514Z","end":"2026-02-12T22:42:20.309495Z","steps":["trace[1026638636] 'read index received' (duration: 304.120529ms)","trace[1026638636] 'applied index is now lower than readState.Index' (duration: 1.341859102s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:42:20.309558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646039852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:20.309581Z","caller":"traceutil/trace.go:171","msg":"trace[1907962411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:363; }","duration":"1.646078653s","start":"2026-02-12T22:42:18.663495Z","end":"2026-02-12T22:42:20.309573Z","steps":["trace[1907962411] 'agreement among raft nodes before linearized reading' (duration: 1.646037333s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:20.309609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.663334Z","time spent":"1.646266457s","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:42:20.30979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.663123Z","time spent":"1.646663826s","remote":"127.0.0.1:48612","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T22:42:20.337147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.369619585s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T22:42:20.337565Z","caller":"traceutil/trace.go:171","msg":"trace[402768052] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.67351678s","start":"2026-02-12T22:42:18.664033Z","end":"2026-02-12T22:42:20.33755Z","steps":["trace[402768052] 'process raft request' (duration: 1.673493389s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:20.337646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.664029Z","time spent":"1.673562261s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:20.337895Z","caller":"traceutil/trace.go:171","msg":"trace[896189608] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"1.674010221s","start":"2026-02-12T22:42:18.663879Z","end":"2026-02-12T22:42:20.337889Z","steps":["trace[896189608] 'process raft request' (duration: 1.673497639s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:20.33794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.663865Z","time spent":"1.674057493s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:20.338002Z","caller":"traceutil/trace.go:171","msg":"trace[494123585] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"1.674050073s","start":"2026-02-12T22:42:18.663947Z","end":"2026-02-12T22:42:20.337997Z","steps":["trace[494123585] 'process raft request' (duration: 1.67353669s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:20.338036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.663943Z","time spent":"1.674070613s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:20.338114Z","caller":"traceutil/trace.go:171","msg":"trace[78874600] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.674105763s","start":"2026-02-12T22:42:18.664003Z","end":"2026-02-12T22:42:20.338109Z","steps":["trace[78874600] 'process raft request' (duration: 1.673505729s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:20.338143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:18.663998Z","time spent":"1.674129535s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:20.343355Z","caller":"traceutil/trace.go:171","msg":"trace[1106821687] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"171.777908ms","start":"2026-02-12T22:42:20.171563Z","end":"2026-02-12T22:42:20.343341Z","steps":["trace[1106821687] 'process raft request' (duration: 171.303987ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:39.713711Z","caller":"traceutil/trace.go:171","msg":"trace[436464688] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"119.140833ms","start":"2026-02-12T22:42:39.594548Z","end":"2026-02-12T22:42:39.713689Z","steps":["trace[436464688] 'process raft request' (duration: 119.035591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:40.741055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870727054844749,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T22:42:40.900516Z","caller":"traceutil/trace.go:171","msg":"trace[732542390] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"660.099049ms","start":"2026-02-12T22:42:40.240351Z","end":"2026-02-12T22:42:40.90045Z","steps":["trace[732542390] 'read index received' (duration: 659.989028ms)","trace[732542390] 'applied index is now lower than readState.Index' (duration: 109.201µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:40.900629Z","caller":"traceutil/trace.go:171","msg":"trace[700947996] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"706.406376ms","start":"2026-02-12T22:42:40.194203Z","end":"2026-02-12T22:42:40.90061Z","steps":["trace[700947996] 'process raft request' (duration: 706.137553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:40.900713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.194186Z","time spent":"706.469766ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T22:42:40.900751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.372122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:40.900799Z","caller":"traceutil/trace.go:171","msg":"trace[1322546972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"660.497774ms","start":"2026-02-12T22:42:40.240287Z","end":"2026-02-12T22:42:40.900785Z","steps":["trace[1322546972] 'agreement among raft nodes before linearized reading' (duration: 660.375922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:40.900853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.240263Z","time spent":"660.582394ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:42:41.401454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870727054844754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T22:42:41.901912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415870727054844754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T22:42:41.972752Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072526594s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T22:42:41.973185Z","caller":"traceutil/trace.go:171","msg":"trace[2014201001] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.210745608s","start":"2026-02-12T22:42:40.76242Z","end":"2026-02-12T22:42:41.973165Z","steps":["trace[2014201001] 'process raft request' (duration: 1.210585406s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:41.973334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.762401Z","time spent":"1.2108692s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:41.973762Z","caller":"traceutil/trace.go:171","msg":"trace[1553051319] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.201307879s","start":"2026-02-12T22:42:40.772443Z","end":"2026-02-12T22:42:41.97375Z","steps":["trace[1553051319] 'process raft request' (duration: 1.200682552s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:41.97405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.772429Z","time spent":"1.201582652s","remote":"127.0.0.1:48766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4365,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:41.974285Z","caller":"traceutil/trace.go:171","msg":"trace[1782783993] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.178342502s","start":"2026-02-12T22:42:40.795932Z","end":"2026-02-12T22:42:41.974275Z","steps":["trace[1782783993] 'process raft request' (duration: 1.177575623s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:41.974421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.795906Z","time spent":"1.178484704s","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T22:42:42.536325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.942520988s","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":"info","ts":"2026-02-12T22:42:42.536395Z","caller":"traceutil/trace.go:171","msg":"trace[1074123980] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:515; }","duration":"1.942631059s","start":"2026-02-12T22:42:40.593747Z","end":"2026-02-12T22:42:42.536378Z","steps":["trace[1074123980] 'agreement among raft nodes before linearized reading' (duration: 1.942448147s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.536445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.593724Z","time spent":"1.94271175s","remote":"127.0.0.1:48636","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":"info","ts":"2026-02-12T22:42:42.536703Z","caller":"traceutil/trace.go:171","msg":"trace[502021723] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:526; }","duration":"1.635575687s","start":"2026-02-12T22:42:40.900585Z","end":"2026-02-12T22:42:42.536161Z","steps":["trace[502021723] 'read index received' (duration: 1.072540045s)","trace[502021723] 'applied index is now lower than readState.Index' (duration: 563.034752ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:42.536697Z","caller":"traceutil/trace.go:171","msg":"trace[1546678846] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.628558046s","start":"2026-02-12T22:42:40.908111Z","end":"2026-02-12T22:42:42.536669Z","steps":["trace[1546678846] 'process raft request' (duration: 1.627833807s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.5368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001364791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-12T22:42:42.536829Z","caller":"traceutil/trace.go:171","msg":"trace[922195702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"2.001409501s","start":"2026-02-12T22:42:40.53541Z","end":"2026-02-12T22:42:42.53682Z","steps":["trace[922195702] 'agreement among raft nodes before linearized reading' (duration: 2.001352751s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.536846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.908096Z","time spent":"1.628684237s","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:42:42.536896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00221716s","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-02-12T22:42:42.536921Z","caller":"traceutil/trace.go:171","msg":"trace[118679342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"2.002262161s","start":"2026-02-12T22:42:40.534652Z","end":"2026-02-12T22:42:42.536914Z","steps":["trace[118679342] 'agreement among raft nodes before linearized reading' (duration: 2.002223891s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.536947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.534639Z","time spent":"2.002302332s","remote":"127.0.0.1:48862","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":"info","ts":"2026-02-12T22:42:42.537026Z","caller":"traceutil/trace.go:171","msg":"trace[1493175724] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"741.222948ms","start":"2026-02-12T22:42:41.795791Z","end":"2026-02-12T22:42:42.537014Z","steps":["trace[1493175724] 'process raft request' (duration: 740.276977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.537215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.006669002s","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-02-12T22:42:42.53729Z","caller":"traceutil/trace.go:171","msg":"trace[1805565949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"2.006773913s","start":"2026-02-12T22:42:40.530507Z","end":"2026-02-12T22:42:42.537281Z","steps":["trace[1805565949] 'agreement among raft nodes before linearized reading' (duration: 2.006636971s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.537291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:41.795771Z","time spent":"741.47316ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T22:42:42.537322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.530491Z","time spent":"2.006821753s","remote":"127.0.0.1:48862","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":"warn","ts":"2026-02-12T22:42:42.537416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.729699318s","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-02-12T22:42:42.537449Z","caller":"traceutil/trace.go:171","msg":"trace[1628540493] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:515; }","duration":"1.729749938s","start":"2026-02-12T22:42:40.807688Z","end":"2026-02-12T22:42:42.537438Z","steps":["trace[1628540493] 'agreement among raft nodes before linearized reading' (duration: 1.729696268s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.53748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.807676Z","time spent":"1.729794729s","remote":"127.0.0.1:48684","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-02-12T22:42:42.536856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.535402Z","time spent":"2.001447592s","remote":"127.0.0.1:48862","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":"warn","ts":"2026-02-12T22:42:42.538992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.145513273s","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":"info","ts":"2026-02-12T22:42:42.539034Z","caller":"traceutil/trace.go:171","msg":"trace[866228014] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:515; }","duration":"2.145590235s","start":"2026-02-12T22:42:40.393433Z","end":"2026-02-12T22:42:42.539023Z","steps":["trace[866228014] 'agreement among raft nodes before linearized reading' (duration: 2.145525794s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.539064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.393421Z","time spent":"2.145636425s","remote":"127.0.0.1:48636","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-02-12T22:42:42.540975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.049738582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-12T22:42:42.541076Z","caller":"traceutil/trace.go:171","msg":"trace[1310905336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"2.053062992s","start":"2026-02-12T22:42:40.487965Z","end":"2026-02-12T22:42:42.541028Z","steps":["trace[1310905336] 'agreement among raft nodes before linearized reading' (duration: 2.049699912s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.542628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.487953Z","time spent":"2.053158423s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T22:42:42.544943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642075834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:42.545064Z","caller":"traceutil/trace.go:171","msg":"trace[217513658] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"1.642245927s","start":"2026-02-12T22:42:40.902808Z","end":"2026-02-12T22:42:42.545054Z","steps":["trace[217513658] 'agreement among raft nodes before linearized reading' (duration: 1.641833932s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.545154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.902792Z","time spent":"1.642346188s","remote":"127.0.0.1:48594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:42:42.549829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156169167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-12T22:42:42.557915Z","caller":"traceutil/trace.go:171","msg":"trace[555767902] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"1.156271199s","start":"2026-02-12T22:42:41.393599Z","end":"2026-02-12T22:42:42.549871Z","steps":["trace[555767902] 'agreement among raft nodes before linearized reading' (duration: 1.156111446s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.570132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:41.393575Z","time spent":"1.164391664s","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5200,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-12T22:42:42.570525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376758755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-12T22:42:42.570576Z","caller":"traceutil/trace.go:171","msg":"trace[1973851777] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"1.376824896s","start":"2026-02-12T22:42:41.193722Z","end":"2026-02-12T22:42:42.570547Z","steps":["trace[1973851777] 'agreement among raft nodes before linearized reading' (duration: 1.376707735s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.570614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:41.193672Z","time spent":"1.376935937s","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-02-12T22:42:42.570871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577415612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-12T22:42:42.570899Z","caller":"traceutil/trace.go:171","msg":"trace[1239718762] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"1.577439782s","start":"2026-02-12T22:42:40.993449Z","end":"2026-02-12T22:42:42.570888Z","steps":["trace[1239718762] 'agreement among raft nodes before linearized reading' (duration: 1.577355781s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.570915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.993391Z","time spent":"1.577520673s","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-02-12T22:42:42.571103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656888294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-12T22:42:42.571144Z","caller":"traceutil/trace.go:171","msg":"trace[1617418849] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:516; }","duration":"1.656953554s","start":"2026-02-12T22:42:40.914166Z","end":"2026-02-12T22:42:42.57112Z","steps":["trace[1617418849] 'agreement among raft nodes before linearized reading' (duration: 1.656874174s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.571165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:40.914159Z","time spent":"1.657001375s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T22:42:42.58328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.83326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4074"} {"level":"info","ts":"2026-02-12T22:42:42.583321Z","caller":"traceutil/trace.go:171","msg":"trace[1585413128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"189.911771ms","start":"2026-02-12T22:42:42.393401Z","end":"2026-02-12T22:42:42.583312Z","steps":["trace[1585413128] 'agreement among raft nodes before linearized reading' (duration: 184.448027ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:42.583747Z","caller":"traceutil/trace.go:171","msg":"trace[1144798589] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"554.812419ms","start":"2026-02-12T22:42:42.023043Z","end":"2026-02-12T22:42:42.577856Z","steps":["trace[1144798589] 'process raft request' (duration: 520.688922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.583856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:42.023026Z","time spent":"560.74445ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T22:42:42.584097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.750724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-12T22:42:42.58422Z","caller":"traceutil/trace.go:171","msg":"trace[2113350022] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"390.808546ms","start":"2026-02-12T22:42:42.193307Z","end":"2026-02-12T22:42:42.584116Z","steps":["trace[2113350022] 'agreement among raft nodes before linearized reading' (duration: 384.553631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.584281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:42.193289Z","time spent":"390.950537ms","remote":"127.0.0.1:48636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-12T22:42:42.584436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.883969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-12T22:42:42.58446Z","caller":"traceutil/trace.go:171","msg":"trace[1584033102] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:516; }","duration":"990.945321ms","start":"2026-02-12T22:42:41.593508Z","end":"2026-02-12T22:42:42.584454Z","steps":["trace[1584033102] 'agreement among raft nodes before linearized reading' (duration: 984.355092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.584576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:41.593434Z","time spent":"991.092812ms","remote":"127.0.0.1:48636","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":"info","ts":"2026-02-12T22:42:42.702976Z","caller":"traceutil/trace.go:171","msg":"trace[1489664236] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:532; }","duration":"117.918105ms","start":"2026-02-12T22:42:42.585045Z","end":"2026-02-12T22:42:42.702963Z","steps":["trace[1489664236] 'read index received' (duration: 66.368481ms)","trace[1489664236] 'applied index is now lower than readState.Index' (duration: 51.549034ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:42.703114Z","caller":"traceutil/trace.go:171","msg":"trace[1256657430] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"128.645463ms","start":"2026-02-12T22:42:42.574462Z","end":"2026-02-12T22:42:42.703107Z","steps":["trace[1256657430] 'process raft request' (duration: 76.945597ms)","trace[1256657430] 'compare' (duration: 51.341312ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:42.7032Z","caller":"traceutil/trace.go:171","msg":"trace[349992739] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"128.611022ms","start":"2026-02-12T22:42:42.574585Z","end":"2026-02-12T22:42:42.703196Z","steps":["trace[349992739] 'process raft request' (duration: 128.273028ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:42.703247Z","caller":"traceutil/trace.go:171","msg":"trace[1478389043] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"128.617382ms","start":"2026-02-12T22:42:42.574626Z","end":"2026-02-12T22:42:42.703243Z","steps":["trace[1478389043] 'process raft request' (duration: 128.253788ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:42.70334Z","caller":"traceutil/trace.go:171","msg":"trace[161599614] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"125.677547ms","start":"2026-02-12T22:42:42.577659Z","end":"2026-02-12T22:42:42.703336Z","steps":["trace[161599614] 'process raft request' (duration: 125.238222ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:42.70339Z","caller":"traceutil/trace.go:171","msg":"trace[565794148] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"120.178672ms","start":"2026-02-12T22:42:42.583208Z","end":"2026-02-12T22:42:42.703387Z","steps":["trace[565794148] 'process raft request' (duration: 119.720617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.703493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.436251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:42.703509Z","caller":"traceutil/trace.go:171","msg":"trace[865835446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"118.473751ms","start":"2026-02-12T22:42:42.585031Z","end":"2026-02-12T22:42:42.703504Z","steps":["trace[865835446] 'agreement among raft nodes before linearized reading' (duration: 118.436601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.703576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.087075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13220"} {"level":"info","ts":"2026-02-12T22:42:42.703588Z","caller":"traceutil/trace.go:171","msg":"trace[331083865] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:523; }","duration":"107.099295ms","start":"2026-02-12T22:42:42.596485Z","end":"2026-02-12T22:42:42.703584Z","steps":["trace[331083865] 'agreement among raft nodes before linearized reading' (duration: 107.078095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:42.946834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.427729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:42:42.946918Z","caller":"traceutil/trace.go:171","msg":"trace[2059737772] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"200.101252ms","start":"2026-02-12T22:42:42.746804Z","end":"2026-02-12T22:42:42.946906Z","steps":["trace[2059737772] 'process raft request' (duration: 77.549762ms)","trace[2059737772] 'compare' (duration: 122.347968ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:42.950032Z","caller":"traceutil/trace.go:171","msg":"trace[252508645] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"115.175802ms","start":"2026-02-12T22:42:42.834831Z","end":"2026-02-12T22:42:42.950007Z","steps":["trace[252508645] 'process raft request' (duration: 114.749557ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:43.033486Z","caller":"traceutil/trace.go:171","msg":"trace[878520713] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"180.398424ms","start":"2026-02-12T22:42:42.853074Z","end":"2026-02-12T22:42:43.033472Z","steps":["trace[878520713] 'process raft request' (duration: 179.756166ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:50.209459Z","caller":"traceutil/trace.go:171","msg":"trace[498371711] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"173.818659ms","start":"2026-02-12T22:42:50.035614Z","end":"2026-02-12T22:42:50.209433Z","steps":["trace[498371711] 'process raft request' (duration: 173.674877ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:50.762041Z","caller":"traceutil/trace.go:171","msg":"trace[841187868] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"117.249321ms","start":"2026-02-12T22:42:50.644776Z","end":"2026-02-12T22:42:50.762025Z","steps":["trace[841187868] 'process raft request' (duration: 116.829765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:52.017786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.484605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:52.017829Z","caller":"traceutil/trace.go:171","msg":"trace[656713041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"780.559955ms","start":"2026-02-12T22:42:51.237258Z","end":"2026-02-12T22:42:52.017818Z","steps":["trace[656713041] 'range keys from in-memory index tree' (duration: 780.426884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:52.01785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:51.237244Z","time spent":"780.601326ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T22:42:52.655283Z","caller":"traceutil/trace.go:171","msg":"trace[938508486] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"605.438634ms","start":"2026-02-12T22:42:52.049836Z","end":"2026-02-12T22:42:52.655275Z","steps":["trace[938508486] 'process raft request' (duration: 605.16756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:52.655366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:52.04982Z","time spent":"605.492804ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:42:52.65511Z","caller":"traceutil/trace.go:171","msg":"trace[840991167] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"415.063477ms","start":"2026-02-12T22:42:52.240031Z","end":"2026-02-12T22:42:52.655095Z","steps":["trace[840991167] 'read index received' (duration: 414.941646ms)","trace[840991167] 'applied index is now lower than readState.Index' (duration: 121.361µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:42:52.65554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.506834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:42:52.655571Z","caller":"traceutil/trace.go:171","msg":"trace[1518386243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"415.537394ms","start":"2026-02-12T22:42:52.240028Z","end":"2026-02-12T22:42:52.655565Z","steps":["trace[1518386243] 'agreement among raft nodes before linearized reading' (duration: 415.495404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:42:52.655588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:42:52.239993Z","time spent":"415.591695ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T22:42:57.216171Z","caller":"traceutil/trace.go:171","msg":"trace[1874855952] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"132.794551ms","start":"2026-02-12T22:42:57.083344Z","end":"2026-02-12T22:42:57.216139Z","steps":["trace[1874855952] 'process raft request' (duration: 113.260009ms)","trace[1874855952] 'compare' (duration: 19.401211ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:42:59.077057Z","caller":"traceutil/trace.go:171","msg":"trace[1112084549] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"105.658724ms","start":"2026-02-12T22:42:58.971378Z","end":"2026-02-12T22:42:59.077037Z","steps":["trace[1112084549] 'process raft request' (duration: 105.621513ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:59.077196Z","caller":"traceutil/trace.go:171","msg":"trace[2070403924] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"107.290497ms","start":"2026-02-12T22:42:58.9699Z","end":"2026-02-12T22:42:59.07719Z","steps":["trace[2070403924] 'process raft request' (duration: 107.029383ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:59.139372Z","caller":"traceutil/trace.go:171","msg":"trace[220468675] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"149.558339ms","start":"2026-02-12T22:42:58.989796Z","end":"2026-02-12T22:42:59.139354Z","steps":["trace[220468675] 'process raft request' (duration: 149.279415ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:42:59.234341Z","caller":"traceutil/trace.go:171","msg":"trace[572355656] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"145.674506ms","start":"2026-02-12T22:42:59.088649Z","end":"2026-02-12T22:42:59.234324Z","steps":["trace[572355656] 'process raft request' (duration: 122.961984ms)","trace[572355656] 'compare' (duration: 22.640871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:44:12.109529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.053345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:44:12.109601Z","caller":"traceutil/trace.go:171","msg":"trace[47053765] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:947; }","duration":"187.177217ms","start":"2026-02-12T22:44:11.922406Z","end":"2026-02-12T22:44:12.109583Z","steps":["trace[47053765] 'count revisions from in-memory index tree' (duration: 186.933603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:12.110228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.159264ms","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-02-12T22:44:12.110262Z","caller":"traceutil/trace.go:171","msg":"trace[880433884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:947; }","duration":"626.194664ms","start":"2026-02-12T22:44:11.48406Z","end":"2026-02-12T22:44:12.110254Z","steps":["trace[880433884] 'range keys from in-memory index tree' (duration: 626.052171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:12.110282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:44:11.484019Z","time spent":"626.257185ms","remote":"127.0.0.1:48862","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":"warn","ts":"2026-02-12T22:44:12.110596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.644712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-12T22:44:12.110625Z","caller":"traceutil/trace.go:171","msg":"trace[1692867129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"552.696472ms","start":"2026-02-12T22:44:11.557921Z","end":"2026-02-12T22:44:12.110618Z","steps":["trace[1692867129] 'range keys from in-memory index tree' (duration: 552.57638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:12.110688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:44:11.55791Z","time spent":"552.753994ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T22:44:12.109564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.480853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:44:12.113901Z","caller":"traceutil/trace.go:171","msg":"trace[1201415205] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:947; }","duration":"501.859407ms","start":"2026-02-12T22:44:11.612003Z","end":"2026-02-12T22:44:12.113862Z","steps":["trace[1201415205] 'count revisions from in-memory index tree' (duration: 497.407352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:12.114102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:44:11.611989Z","time spent":"502.084581ms","remote":"127.0.0.1:35978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-12T22:44:19.975945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.80972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:44:19.976011Z","caller":"traceutil/trace.go:171","msg":"trace[920991880] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"418.063812ms","start":"2026-02-12T22:44:19.557938Z","end":"2026-02-12T22:44:19.976002Z","steps":["trace[920991880] 'read index received' (duration: 56.042µs)","trace[920991880] 'applied index is now lower than readState.Index' (duration: 418.00708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:44:19.976095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.185875ms","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-02-12T22:44:19.976108Z","caller":"traceutil/trace.go:171","msg":"trace[1443540757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"418.234926ms","start":"2026-02-12T22:44:19.557868Z","end":"2026-02-12T22:44:19.976103Z","steps":["trace[1443540757] 'agreement among raft nodes before linearized reading' (duration: 418.157645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:19.976123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:44:19.55785Z","time spent":"418.269457ms","remote":"127.0.0.1:48862","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":"info","ts":"2026-02-12T22:44:19.976254Z","caller":"traceutil/trace.go:171","msg":"trace[350162190] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"558.532768ms","start":"2026-02-12T22:44:19.417715Z","end":"2026-02-12T22:44:19.976248Z","steps":["trace[350162190] 'process raft request' (duration: 88.375971ms)","trace[350162190] 'compare' (duration: 469.739709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:44:19.976281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:44:19.417703Z","time spent":"558.563979ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:44:20.353357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.532303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-12T22:44:20.353852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.733132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-12T22:44:20.353903Z","caller":"traceutil/trace.go:171","msg":"trace[260794190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:977; }","duration":"106.802955ms","start":"2026-02-12T22:44:20.247085Z","end":"2026-02-12T22:44:20.353888Z","steps":["trace[260794190] 'range keys from in-memory index tree' (duration: 106.657431ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:44:20.353842Z","caller":"traceutil/trace.go:171","msg":"trace[182498237] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:977; }","duration":"240.055044ms","start":"2026-02-12T22:44:20.113765Z","end":"2026-02-12T22:44:20.35382Z","steps":["trace[182498237] 'count revisions from in-memory index tree' (duration: 239.440011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:20.353764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.221581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:44:20.354889Z","caller":"traceutil/trace.go:171","msg":"trace[832241282] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:977; }","duration":"215.373382ms","start":"2026-02-12T22:44:20.139502Z","end":"2026-02-12T22:44:20.354875Z","steps":["trace[832241282] 'range keys from in-memory index tree' (duration: 214.17668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:20.353793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.731359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:44:20.3554Z","caller":"traceutil/trace.go:171","msg":"trace[912177023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"115.34552ms","start":"2026-02-12T22:44:20.240042Z","end":"2026-02-12T22:44:20.355388Z","steps":["trace[912177023] 'range keys from in-memory index tree' (duration: 113.650148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:20.353686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.116684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-12T22:44:20.357645Z","caller":"traceutil/trace.go:171","msg":"trace[42868961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:977; }","duration":"105.099641ms","start":"2026-02-12T22:44:20.252531Z","end":"2026-02-12T22:44:20.35763Z","steps":["trace[42868961] 'range keys from in-memory index tree' (duration: 100.993891ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:44:20.721505Z","caller":"traceutil/trace.go:171","msg":"trace[1791881792] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"358.569574ms","start":"2026-02-12T22:44:20.362919Z","end":"2026-02-12T22:44:20.721488Z","steps":["trace[1791881792] 'process raft request' (duration: 358.474772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:20.721606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:44:20.362901Z","time spent":"358.653625ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:44:20.729103Z","caller":"traceutil/trace.go:171","msg":"trace[665328155] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"364.94892ms","start":"2026-02-12T22:44:20.364134Z","end":"2026-02-12T22:44:20.729082Z","steps":["trace[665328155] 'process raft request' (duration: 364.846828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:44:20.72924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:44:20.364128Z","time spent":"365.052042ms","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}