{"level":"warn","ts":"2026-02-03T12:56:32.226509Z","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-03T12:56:32.226654Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149: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.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149: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-03T12:56:32.226742Z","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-03T12:56:32.226752Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-03T12:56:32.22679Z","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-03T12:56:32.232843Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-02-03T12:56:32.233014Z","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.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149: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-03T12:56:32.243764Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.523329ms"} {"level":"info","ts":"2026-02-03T12:56:32.254244Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-02-03T12:56:32.25431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T12:56:32.254363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-02-03T12:56:32.254371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T12:56:32.25438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-02-03T12:56:32.25441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-02-03T12:56:32.272158Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T12:56:32.291912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T12:56:32.295529Z","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-03T12:56:32.297468Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T12:56:32.300171Z","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-03T12:56:32.301678Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T12:56:32.302005Z","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-03T12:56:32.302038Z","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-03T12:56:32.302105Z","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-03T12:56:32.302356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-02-03T12:56:32.302508Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-02-03T12:56:32.302883Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T12:56:32.302906Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T12:56:32.303417Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-03T12:56:32.303433Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-02-03T12:56:32.355913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T12:56:32.356026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T12:56:32.357445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-02-03T12:56:32.357463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-02-03T12:56:32.357535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-03T12:56:32.35759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-02-03T12:56:32.357604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-02-03T12:56:32.361962Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:56:32.36514Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T12:56:32.365867Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T12:56:32.366094Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:56:32.366197Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:56:32.366231Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T12:56:32.366299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T12:56:32.366306Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T12:56:32.366363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T12:56:32.368051Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T12:56:32.368866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-02-03T12:56:35.854828Z","caller":"traceutil/trace.go:171","msg":"trace[1135993847] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"162.333707ms","start":"2026-02-03T12:56:35.692465Z","end":"2026-02-03T12:56:35.854798Z","steps":["trace[1135993847] 'process raft request' (duration: 99.62371ms)","trace[1135993847] 'compare' (duration: 62.363019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:56:35.855701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.97071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-03T12:56:35.855984Z","caller":"traceutil/trace.go:171","msg":"trace[1572833280] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:77; }","duration":"163.276237ms","start":"2026-02-03T12:56:35.692691Z","end":"2026-02-03T12:56:35.855967Z","steps":["trace[1572833280] 'agreement among raft nodes before linearized reading' (duration: 162.907459ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:56:35.855549Z","caller":"traceutil/trace.go:171","msg":"trace[1521917929] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"161.907787ms","start":"2026-02-03T12:56:35.692706Z","end":"2026-02-03T12:56:35.854614Z","steps":["trace[1521917929] 'read index received' (duration: 99.318613ms)","trace[1521917929] 'applied index is now lower than readState.Index' (duration: 62.588024ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:56:35.856985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.071063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-03T12:56:35.857191Z","caller":"traceutil/trace.go:171","msg":"trace[378255989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"128.349779ms","start":"2026-02-03T12:56:35.728801Z","end":"2026-02-03T12:56:35.857151Z","steps":["trace[378255989] 'agreement among raft nodes before linearized reading' (duration: 127.93043ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:05.438987Z","caller":"traceutil/trace.go:171","msg":"trace[548934891] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"203.749469ms","start":"2026-02-03T12:57:05.235223Z","end":"2026-02-03T12:57:05.438972Z","steps":["trace[548934891] 'process raft request' (duration: 203.682577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:05.746955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.573139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T12:57:05.747074Z","caller":"traceutil/trace.go:171","msg":"trace[577351124] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"467.205978ms","start":"2026-02-03T12:57:05.27984Z","end":"2026-02-03T12:57:05.747046Z","steps":["trace[577351124] 'read index received' (duration: 159.917262ms)","trace[577351124] 'applied index is now lower than readState.Index' (duration: 307.287516ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:57:05.747361Z","caller":"traceutil/trace.go:171","msg":"trace[1330984939] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"473.459417ms","start":"2026-02-03T12:57:05.273886Z","end":"2026-02-03T12:57:05.747345Z","steps":["trace[1330984939] 'process raft request' (duration: 367.833444ms)","trace[1330984939] 'compare' (duration: 104.447616ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:05.747442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:05.273831Z","time spent":"473.571079ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:57:05.747646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.807442ms","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-03T12:57:05.747684Z","caller":"traceutil/trace.go:171","msg":"trace[528775667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"467.871062ms","start":"2026-02-03T12:57:05.279803Z","end":"2026-02-03T12:57:05.747674Z","steps":["trace[528775667] 'agreement among raft nodes before linearized reading' (duration: 467.806431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:05.747728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:05.279785Z","time spent":"467.933334ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T12:57:05.794665Z","caller":"traceutil/trace.go:171","msg":"trace[178161232] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"350.809456ms","start":"2026-02-03T12:57:05.443836Z","end":"2026-02-03T12:57:05.794646Z","steps":["trace[178161232] 'process raft request' (duration: 350.757625ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:05.794994Z","caller":"traceutil/trace.go:171","msg":"trace[333844989] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"356.133324ms","start":"2026-02-03T12:57:05.438815Z","end":"2026-02-03T12:57:05.794948Z","steps":["trace[333844989] 'process raft request' (duration: 355.683894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:05.79514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:05.438798Z","time spent":"356.296167ms","remote":"127.0.0.1:56752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-03T12:57:05.795348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:05.443816Z","time spent":"351.489741ms","remote":"127.0.0.1:56752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T12:57:05.98755Z","caller":"traceutil/trace.go:171","msg":"trace[75887400] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"240.419725ms","start":"2026-02-03T12:57:05.7471Z","end":"2026-02-03T12:57:05.98752Z","steps":["trace[75887400] 'read index received' (duration: 47.540789ms)","trace[75887400] 'applied index is now lower than readState.Index' (duration: 192.878436ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:57:05.987617Z","caller":"traceutil/trace.go:171","msg":"trace[2087615534] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"345.860354ms","start":"2026-02-03T12:57:05.641752Z","end":"2026-02-03T12:57:05.987612Z","steps":["trace[2087615534] 'process raft request' (duration: 345.607188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:05.987674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:05.641735Z","time spent":"345.897355ms","remote":"127.0.0.1:56752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-03T12:57:05.987778Z","caller":"traceutil/trace.go:171","msg":"trace[1964309442] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"235.665541ms","start":"2026-02-03T12:57:05.75209Z","end":"2026-02-03T12:57:05.987755Z","steps":["trace[1964309442] 'process raft request' (duration: 235.393524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:05.987832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.450255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:05.987865Z","caller":"traceutil/trace.go:171","msg":"trace[114069121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"577.510316ms","start":"2026-02-03T12:57:05.410349Z","end":"2026-02-03T12:57:05.98786Z","steps":["trace[114069121] 'agreement among raft nodes before linearized reading' (duration: 577.452285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:05.987879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:05.410336Z","time spent":"577.539587ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T12:57:06.071717Z","caller":"traceutil/trace.go:171","msg":"trace[1262305748] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"257.64887ms","start":"2026-02-03T12:57:05.814052Z","end":"2026-02-03T12:57:06.071701Z","steps":["trace[1262305748] 'process raft request' (duration: 257.376264ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:06.072028Z","caller":"traceutil/trace.go:171","msg":"trace[1352565620] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"252.115406ms","start":"2026-02-03T12:57:05.819895Z","end":"2026-02-03T12:57:06.072011Z","steps":["trace[1352565620] 'process raft request' (duration: 251.609344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:06.072145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.878272ms","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-03T12:57:06.072175Z","caller":"traceutil/trace.go:171","msg":"trace[1578077786] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:519; }","duration":"238.934383ms","start":"2026-02-03T12:57:05.83323Z","end":"2026-02-03T12:57:06.072165Z","steps":["trace[1578077786] 'agreement among raft nodes before linearized reading' (duration: 238.890182ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:13.080322Z","caller":"traceutil/trace.go:171","msg":"trace[1246547593] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"104.532579ms","start":"2026-02-03T12:57:12.975766Z","end":"2026-02-03T12:57:13.080299Z","steps":["trace[1246547593] 'process raft request' (duration: 104.048288ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:13.931437Z","caller":"traceutil/trace.go:171","msg":"trace[741973292] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"845.223319ms","start":"2026-02-03T12:57:13.086193Z","end":"2026-02-03T12:57:13.931416Z","steps":["trace[741973292] 'process raft request' (duration: 845.099806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.931567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:13.086178Z","time spent":"845.323741ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T12:57:13.939616Z","caller":"traceutil/trace.go:171","msg":"trace[2031661305] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"293.239413ms","start":"2026-02-03T12:57:13.64636Z","end":"2026-02-03T12:57:13.939599Z","steps":["trace[2031661305] 'read index received' (duration: 284.910858ms)","trace[2031661305] 'applied index is now lower than readState.Index' (duration: 8.327845ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:57:13.939776Z","caller":"traceutil/trace.go:171","msg":"trace[691272294] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"853.100224ms","start":"2026-02-03T12:57:13.086669Z","end":"2026-02-03T12:57:13.939769Z","steps":["trace[691272294] 'process raft request' (duration: 852.859049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.939849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:13.086661Z","time spent":"853.134895ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:57:13.939968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.605301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.939989Z","caller":"traceutil/trace.go:171","msg":"trace[579504319] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:555; }","duration":"293.647652ms","start":"2026-02-03T12:57:13.646334Z","end":"2026-02-03T12:57:13.939982Z","steps":["trace[579504319] 'agreement among raft nodes before linearized reading' (duration: 293.610751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.681313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940112Z","caller":"traceutil/trace.go:171","msg":"trace[1301368807] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:555; }","duration":"293.715263ms","start":"2026-02-03T12:57:13.646391Z","end":"2026-02-03T12:57:13.940106Z","steps":["trace[1301368807] 'agreement among raft nodes before linearized reading' (duration: 293.682823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.080686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940235Z","caller":"traceutil/trace.go:171","msg":"trace[1546588871] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:555; }","duration":"257.099906ms","start":"2026-02-03T12:57:13.68313Z","end":"2026-02-03T12:57:13.94023Z","steps":["trace[1546588871] 'agreement among raft nodes before linearized reading' (duration: 257.072525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.460225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940479Z","caller":"traceutil/trace.go:171","msg":"trace[109713904] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:555; }","duration":"283.503335ms","start":"2026-02-03T12:57:13.656968Z","end":"2026-02-03T12:57:13.940471Z","steps":["trace[109713904] 'agreement among raft nodes before linearized reading' (duration: 283.452394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.784542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940577Z","caller":"traceutil/trace.go:171","msg":"trace[376943752] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:555; }","duration":"283.817133ms","start":"2026-02-03T12:57:13.656754Z","end":"2026-02-03T12:57:13.940571Z","steps":["trace[376943752] 'agreement among raft nodes before linearized reading' (duration: 283.771772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.568118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940628Z","caller":"traceutil/trace.go:171","msg":"trace[1516359968] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:555; }","duration":"257.602199ms","start":"2026-02-03T12:57:13.68302Z","end":"2026-02-03T12:57:13.940622Z","steps":["trace[1516359968] 'agreement among raft nodes before linearized reading' (duration: 257.568838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.112039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940672Z","caller":"traceutil/trace.go:171","msg":"trace[58400864] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:555; }","duration":"284.14151ms","start":"2026-02-03T12:57:13.656525Z","end":"2026-02-03T12:57:13.940667Z","steps":["trace[58400864] 'agreement among raft nodes before linearized reading' (duration: 284.115779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.890305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940721Z","caller":"traceutil/trace.go:171","msg":"trace[512707517] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:555; }","duration":"257.923236ms","start":"2026-02-03T12:57:13.682793Z","end":"2026-02-03T12:57:13.940716Z","steps":["trace[512707517] 'agreement among raft nodes before linearized reading' (duration: 257.894815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.309536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940754Z","caller":"traceutil/trace.go:171","msg":"trace[677566265] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:555; }","duration":"294.340266ms","start":"2026-02-03T12:57:13.646409Z","end":"2026-02-03T12:57:13.940749Z","steps":["trace[677566265] 'agreement among raft nodes before linearized reading' (duration: 294.317336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.63118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.94081Z","caller":"traceutil/trace.go:171","msg":"trace[852594308] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:555; }","duration":"271.647931ms","start":"2026-02-03T12:57:13.669157Z","end":"2026-02-03T12:57:13.940805Z","steps":["trace[852594308] 'agreement among raft nodes before linearized reading' (duration: 271.62222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.756164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940909Z","caller":"traceutil/trace.go:171","msg":"trace[1966967250] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:555; }","duration":"271.772804ms","start":"2026-02-03T12:57:13.669132Z","end":"2026-02-03T12:57:13.940905Z","steps":["trace[1966967250] 'agreement among raft nodes before linearized reading' (duration: 271.742803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:13.940981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.869456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:13.940997Z","caller":"traceutil/trace.go:171","msg":"trace[255792692] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:555; }","duration":"271.898748ms","start":"2026-02-03T12:57:13.669093Z","end":"2026-02-03T12:57:13.940992Z","steps":["trace[255792692] 'agreement among raft nodes before linearized reading' (duration: 271.875247ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:23.38788Z","caller":"traceutil/trace.go:171","msg":"trace[1319175639] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"264.612655ms","start":"2026-02-03T12:57:23.123251Z","end":"2026-02-03T12:57:23.387864Z","steps":["trace[1319175639] 'process raft request' (duration: 264.507802ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:35.726655Z","caller":"traceutil/trace.go:171","msg":"trace[1940349377] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"214.007619ms","start":"2026-02-03T12:57:35.512616Z","end":"2026-02-03T12:57:35.726624Z","steps":["trace[1940349377] 'process raft request' (duration: 213.867876ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:48.264297Z","caller":"traceutil/trace.go:171","msg":"trace[2131582820] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"111.27664ms","start":"2026-02-03T12:57:48.153001Z","end":"2026-02-03T12:57:48.264278Z","steps":["trace[2131582820] 'read index received' (duration: 111.127136ms)","trace[2131582820] 'applied index is now lower than readState.Index' (duration: 148.594µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:48.264482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.462083ms","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-03T12:57:48.264538Z","caller":"traceutil/trace.go:171","msg":"trace[1837122887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:754; }","duration":"111.565146ms","start":"2026-02-03T12:57:48.152961Z","end":"2026-02-03T12:57:48.264526Z","steps":["trace[1837122887] 'agreement among raft nodes before linearized reading' (duration: 111.394012ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:48.264544Z","caller":"traceutil/trace.go:171","msg":"trace[1377170819] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"112.437015ms","start":"2026-02-03T12:57:48.152086Z","end":"2026-02-03T12:57:48.264523Z","steps":["trace[1377170819] 'process raft request' (duration: 112.061337ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:49.830185Z","caller":"traceutil/trace.go:171","msg":"trace[1195043801] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"163.401566ms","start":"2026-02-03T12:57:49.666736Z","end":"2026-02-03T12:57:49.830137Z","steps":["trace[1195043801] 'process raft request' (duration: 163.011348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:50.314012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.806965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T12:57:50.314099Z","caller":"traceutil/trace.go:171","msg":"trace[393278300] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"456.677415ms","start":"2026-02-03T12:57:49.857405Z","end":"2026-02-03T12:57:50.314083Z","steps":["trace[393278300] 'read index received' (duration: 265.639965ms)","trace[393278300] 'applied index is now lower than readState.Index' (duration: 191.03639ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:57:50.314307Z","caller":"traceutil/trace.go:171","msg":"trace[379388480] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"479.596524ms","start":"2026-02-03T12:57:49.834697Z","end":"2026-02-03T12:57:50.314294Z","steps":["trace[379388480] 'process raft request' (duration: 288.430111ms)","trace[379388480] 'compare' (duration: 190.682391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:50.314366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:49.834674Z","time spent":"479.660036ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:57:50.314595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.183976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T12:57:50.314628Z","caller":"traceutil/trace.go:171","msg":"trace[1751097636] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:759; }","duration":"457.217827ms","start":"2026-02-03T12:57:49.857399Z","end":"2026-02-03T12:57:50.314617Z","steps":["trace[1751097636] 'agreement among raft nodes before linearized reading' (duration: 457.119255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:50.314656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:49.857359Z","time spent":"457.289249ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-03T12:57:50.823916Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2594526536953824616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T12:57:51.110887Z","caller":"traceutil/trace.go:171","msg":"trace[2112295302] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"787.67928ms","start":"2026-02-03T12:57:50.323144Z","end":"2026-02-03T12:57:51.110823Z","steps":["trace[2112295302] 'read index received' (duration: 787.463616ms)","trace[2112295302] 'applied index is now lower than readState.Index' (duration: 214.834µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:51.111318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.085949ms","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-03T12:57:51.111432Z","caller":"traceutil/trace.go:171","msg":"trace[18194766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:760; }","duration":"788.282333ms","start":"2026-02-03T12:57:50.323131Z","end":"2026-02-03T12:57:51.111413Z","steps":["trace[18194766] 'agreement among raft nodes before linearized reading' (duration: 787.934886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:51.111482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:50.323125Z","time spent":"788.345955ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-03T12:57:51.111746Z","caller":"traceutil/trace.go:171","msg":"trace[469735853] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"790.799179ms","start":"2026-02-03T12:57:50.320935Z","end":"2026-02-03T12:57:51.111734Z","steps":["trace[469735853] 'process raft request' (duration: 789.736726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:51.111833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:50.320912Z","time spent":"790.866741ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:57:51.111457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.876321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T12:57:51.112Z","caller":"traceutil/trace.go:171","msg":"trace[1266955397] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:760; }","duration":"212.419783ms","start":"2026-02-03T12:57:50.899573Z","end":"2026-02-03T12:57:51.111993Z","steps":["trace[1266955397] 'agreement among raft nodes before linearized reading' (duration: 211.82506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:51.111379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.705236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:51.112147Z","caller":"traceutil/trace.go:171","msg":"trace[502645882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"699.510763ms","start":"2026-02-03T12:57:50.412629Z","end":"2026-02-03T12:57:51.11214Z","steps":["trace[502645882] 'agreement among raft nodes before linearized reading' (duration: 698.712346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:51.112163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:50.412617Z","time spent":"699.539644ms","remote":"127.0.0.1:56708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T12:57:51.11217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.692668ms","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-03T12:57:51.112234Z","caller":"traceutil/trace.go:171","msg":"trace[141732984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"617.79937ms","start":"2026-02-03T12:57:50.494422Z","end":"2026-02-03T12:57:51.112221Z","steps":["trace[141732984] 'agreement among raft nodes before linearized reading' (duration: 617.682177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:51.112263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:50.494409Z","time spent":"617.846672ms","remote":"127.0.0.1:56936","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-03T12:57:51.112385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.676216ms","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-03T12:57:51.112401Z","caller":"traceutil/trace.go:171","msg":"trace[943780673] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"602.707697ms","start":"2026-02-03T12:57:50.509689Z","end":"2026-02-03T12:57:51.112397Z","steps":["trace[943780673] 'agreement among raft nodes before linearized reading' (duration: 602.636895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:57:51.112413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:57:50.50968Z","time spent":"602.730767ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-03T12:57:52.566874Z","caller":"traceutil/trace.go:171","msg":"trace[413606745] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"156.125846ms","start":"2026-02-03T12:57:52.410721Z","end":"2026-02-03T12:57:52.566847Z","steps":["trace[413606745] 'read index received' (duration: 155.978692ms)","trace[413606745] 'applied index is now lower than readState.Index' (duration: 146.494µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:57:52.56698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.245528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:57:52.567004Z","caller":"traceutil/trace.go:171","msg":"trace[968357519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"156.311209ms","start":"2026-02-03T12:57:52.410685Z","end":"2026-02-03T12:57:52.566996Z","steps":["trace[968357519] 'agreement among raft nodes before linearized reading' (duration: 156.238887ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:52.567277Z","caller":"traceutil/trace.go:171","msg":"trace[1243591450] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"246.866799ms","start":"2026-02-03T12:57:52.320403Z","end":"2026-02-03T12:57:52.56727Z","steps":["trace[1243591450] 'process raft request' (duration: 246.340827ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:57:57.250466Z","caller":"traceutil/trace.go:171","msg":"trace[419738838] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"114.00125ms","start":"2026-02-03T12:57:57.136446Z","end":"2026-02-03T12:57:57.250447Z","steps":["trace[419738838] 'process raft request' (duration: 113.892728ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:58:12.222025Z","caller":"traceutil/trace.go:171","msg":"trace[1597554987] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"247.629535ms","start":"2026-02-03T12:58:11.974379Z","end":"2026-02-03T12:58:12.222008Z","steps":["trace[1597554987] 'read index received' (duration: 221.021225ms)","trace[1597554987] 'applied index is now lower than readState.Index' (duration: 26.60733ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:58:12.222117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.722638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:58:12.222135Z","caller":"traceutil/trace.go:171","msg":"trace[759760821] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:870; }","duration":"247.780638ms","start":"2026-02-03T12:58:11.974348Z","end":"2026-02-03T12:58:12.222128Z","steps":["trace[759760821] 'agreement among raft nodes before linearized reading' (duration: 247.725857ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:58:20.87769Z","caller":"traceutil/trace.go:171","msg":"trace[1092467290] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"127.9608ms","start":"2026-02-03T12:58:20.749712Z","end":"2026-02-03T12:58:20.877673Z","steps":["trace[1092467290] 'process raft request' (duration: 127.817717ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:58:21.695767Z","caller":"traceutil/trace.go:171","msg":"trace[691271052] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"219.933181ms","start":"2026-02-03T12:58:21.475818Z","end":"2026-02-03T12:58:21.695751Z","steps":["trace[691271052] 'read index received' (duration: 138.139966ms)","trace[691271052] 'applied index is now lower than readState.Index' (duration: 81.792655ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:58:21.695897Z","caller":"traceutil/trace.go:171","msg":"trace[1839935980] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"230.835373ms","start":"2026-02-03T12:58:21.465055Z","end":"2026-02-03T12:58:21.69589Z","steps":["trace[1839935980] 'process raft request' (duration: 153.870145ms)","trace[1839935980] 'compare' (duration: 76.58582ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:58:21.696039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.208577ms","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-03T12:58:21.696056Z","caller":"traceutil/trace.go:171","msg":"trace[2058442812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"220.250109ms","start":"2026-02-03T12:58:21.4758Z","end":"2026-02-03T12:58:21.69605Z","steps":["trace[2058442812] 'agreement among raft nodes before linearized reading' (duration: 220.180007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:58:21.696147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.158409ms","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-03T12:58:21.69616Z","caller":"traceutil/trace.go:171","msg":"trace[1512954603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"212.19843ms","start":"2026-02-03T12:58:21.483958Z","end":"2026-02-03T12:58:21.696156Z","steps":["trace[1512954603] 'agreement among raft nodes before linearized reading' (duration: 212.160049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:58:21.696247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.116946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:58:21.696259Z","caller":"traceutil/trace.go:171","msg":"trace[827659903] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:896; }","duration":"179.158376ms","start":"2026-02-03T12:58:21.517097Z","end":"2026-02-03T12:58:21.696255Z","steps":["trace[827659903] 'agreement among raft nodes before linearized reading' (duration: 179.135125ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:58:21.81455Z","caller":"traceutil/trace.go:171","msg":"trace[383550378] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"114.201295ms","start":"2026-02-03T12:58:21.700333Z","end":"2026-02-03T12:58:21.814535Z","steps":["trace[383550378] 'process raft request' (duration: 100.936291ms)","trace[383550378] 'compare' (duration: 13.187882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:58:35.86679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.244601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-03T12:58:35.877568Z","caller":"traceutil/trace.go:171","msg":"trace[1472098540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:953; }","duration":"100.381064ms","start":"2026-02-03T12:58:35.766473Z","end":"2026-02-03T12:58:35.866854Z","steps":["trace[1472098540] 'range keys from in-memory index tree' (duration: 100.046047ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:58:36.220316Z","caller":"traceutil/trace.go:171","msg":"trace[38481369] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"339.15123ms","start":"2026-02-03T12:58:35.881138Z","end":"2026-02-03T12:58:36.220289Z","steps":["trace[38481369] 'read index received' (duration: 338.948666ms)","trace[38481369] 'applied index is now lower than readState.Index' (duration: 201.494µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:58:36.220396Z","caller":"traceutil/trace.go:171","msg":"trace[1092983131] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"340.05345ms","start":"2026-02-03T12:58:35.880335Z","end":"2026-02-03T12:58:36.220389Z","steps":["trace[1092983131] 'process raft request' (duration: 339.798515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:58:36.220465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:58:35.880315Z","time spent":"340.098622ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:58:36.220576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.438766ms","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-03T12:58:36.220592Z","caller":"traceutil/trace.go:171","msg":"trace[1239975475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"339.471746ms","start":"2026-02-03T12:58:35.881115Z","end":"2026-02-03T12:58:36.220586Z","steps":["trace[1239975475] 'agreement among raft nodes before linearized reading' (duration: 339.412975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:58:36.220605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:58:35.881103Z","time spent":"339.499617ms","remote":"127.0.0.1:56936","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-03T12:58:36.220662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.438694ms","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-03T12:58:36.220674Z","caller":"traceutil/trace.go:171","msg":"trace[262798284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"307.481335ms","start":"2026-02-03T12:58:35.913188Z","end":"2026-02-03T12:58:36.220669Z","steps":["trace[262798284] 'agreement among raft nodes before linearized reading' (duration: 307.448585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:58:36.220684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:58:35.913172Z","time spent":"307.510146ms","remote":"127.0.0.1:56936","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-03T12:58:36.598635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.007733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T12:58:36.598734Z","caller":"traceutil/trace.go:171","msg":"trace[1064496022] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"373.831002ms","start":"2026-02-03T12:58:36.224886Z","end":"2026-02-03T12:58:36.598717Z","steps":["trace[1064496022] 'process raft request' (duration: 204.679466ms)","trace[1064496022] 'compare' (duration: 168.630355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T12:58:36.599105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:58:36.224869Z","time spent":"374.16761ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T12:58:36.613041Z","caller":"traceutil/trace.go:171","msg":"trace[1609446443] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:989; }","duration":"200.043104ms","start":"2026-02-03T12:58:36.412981Z","end":"2026-02-03T12:58:36.613024Z","steps":["trace[1609446443] 'read index received' (duration: 16.59383ms)","trace[1609446443] 'applied index is now lower than readState.Index' (duration: 183.448754ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T12:58:36.61334Z","caller":"traceutil/trace.go:171","msg":"trace[1863666642] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"388.269123ms","start":"2026-02-03T12:58:36.225061Z","end":"2026-02-03T12:58:36.61333Z","steps":["trace[1863666642] 'process raft request' (duration: 387.877285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:58:36.613421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T12:58:36.225055Z","time spent":"388.311935ms","remote":"127.0.0.1:56936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T12:58:36.613668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.687198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T12:58:36.613693Z","caller":"traceutil/trace.go:171","msg":"trace[999374015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"200.736549ms","start":"2026-02-03T12:58:36.412949Z","end":"2026-02-03T12:58:36.613686Z","steps":["trace[999374015] 'agreement among raft nodes before linearized reading' (duration: 200.692258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T12:58:36.614282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.650174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T12:58:36.614316Z","caller":"traceutil/trace.go:171","msg":"trace[59848593] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:956; }","duration":"190.714346ms","start":"2026-02-03T12:58:36.423591Z","end":"2026-02-03T12:58:36.614305Z","steps":["trace[59848593] 'agreement among raft nodes before linearized reading' (duration: 190.651014ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T12:59:59.31528Z","caller":"traceutil/trace.go:171","msg":"trace[1512055757] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"104.563783ms","start":"2026-02-03T12:59:59.210619Z","end":"2026-02-03T12:59:59.315183Z","steps":["trace[1512055757] 'process raft request' (duration: 101.001374ms)"],"step_count":1}