{"level":"warn","ts":"2026-05-18T15:51:23.217545Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T15:51:23.217665Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.233: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.233:2380","--initial-cluster=instance=https://199.204.45.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.233: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-05-18T15:51:23.21776Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-18T15:51:23.21778Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-18T15:51:23.217812Z","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-05-18T15:51:23.218682Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"]} {"level":"info","ts":"2026-05-18T15:51:23.218888Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.233: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-05-18T15:51:23.22264Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.283542ms"} {"level":"info","ts":"2026-05-18T15:51:23.236673Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"90a0cb2044183f95","cluster-id":"782fef34d5ea94f9"} {"level":"info","ts":"2026-05-18T15:51:23.236769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T15:51:23.236791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 0"} {"level":"info","ts":"2026-05-18T15:51:23.236804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 90a0cb2044183f95 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-18T15:51:23.236812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became follower at term 1"} {"level":"info","ts":"2026-05-18T15:51:23.236847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"warn","ts":"2026-05-18T15:51:23.2462Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T15:51:23.252312Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T15:51:23.254128Z","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-05-18T15:51:23.255971Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"90a0cb2044183f95","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-18T15:51:23.259527Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"90a0cb2044183f95","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-18T15:51:23.260496Z","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-05-18T15:51:23.260575Z","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-05-18T15:51:23.260591Z","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-05-18T15:51:23.262425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 switched to configuration voters=(10421552877177159573)"} {"level":"info","ts":"2026-05-18T15:51:23.262715Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","added-peer-id":"90a0cb2044183f95","added-peer-peer-urls":["https://199.204.45.233:2380"]} {"level":"info","ts":"2026-05-18T15:51:23.264973Z","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-05-18T15:51:23.265211Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-18T15:51:23.265251Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.233:2380"} {"level":"info","ts":"2026-05-18T15:51:23.265554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"90a0cb2044183f95","initial-advertise-peer-urls":["https://199.204.45.233:2380"],"listen-peer-urls":["https://199.204.45.233:2380"],"advertise-client-urls":["https://199.204.45.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-18T15:51:23.265616Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T15:51:23.937991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T15:51:23.938055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T15:51:23.938078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgPreVoteResp from 90a0cb2044183f95 at term 1"} {"level":"info","ts":"2026-05-18T15:51:23.938096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became candidate at term 2"} {"level":"info","ts":"2026-05-18T15:51:23.938107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 received MsgVoteResp from 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-18T15:51:23.938123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"90a0cb2044183f95 became leader at term 2"} {"level":"info","ts":"2026-05-18T15:51:23.938136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 90a0cb2044183f95 elected leader 90a0cb2044183f95 at term 2"} {"level":"info","ts":"2026-05-18T15:51:23.940339Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:51:23.941892Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"90a0cb2044183f95","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.233:2379]}","request-path":"/0/members/90a0cb2044183f95/attributes","cluster-id":"782fef34d5ea94f9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-18T15:51:23.941904Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T15:51:23.942113Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T15:51:23.942312Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"782fef34d5ea94f9","local-member-id":"90a0cb2044183f95","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:51:23.942396Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:51:23.942419Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T15:51:23.942469Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T15:51:23.942483Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T15:51:23.945179Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.233:2379"} {"level":"info","ts":"2026-05-18T15:51:23.945589Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-18T15:51:27.154393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.406339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:51:27.154497Z","caller":"traceutil/trace.go:171","msg":"trace[1647656413] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"360.973094ms","start":"2026-05-18T15:51:26.793507Z","end":"2026-05-18T15:51:27.15448Z","steps":["trace[1647656413] 'read index received' (duration: 245.793231ms)","trace[1647656413] 'applied index is now lower than readState.Index' (duration: 115.178883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:51:27.15458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.080796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:51:27.154599Z","caller":"traceutil/trace.go:171","msg":"trace[886561505] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:225; }","duration":"361.189858ms","start":"2026-05-18T15:51:26.793402Z","end":"2026-05-18T15:51:27.154592Z","steps":["trace[886561505] 'agreement among raft nodes before linearized reading' (duration: 361.120837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:27.154625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:51:26.793377Z","time spent":"361.239819ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-18T15:51:27.154765Z","caller":"traceutil/trace.go:171","msg":"trace[930417275] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"404.305034ms","start":"2026-05-18T15:51:26.750451Z","end":"2026-05-18T15:51:27.154756Z","steps":["trace[930417275] 'process raft request' (duration: 288.828035ms)","trace[930417275] 'compare' (duration: 114.249606ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:51:27.154799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:51:26.750437Z","time spent":"404.344546ms","remote":"127.0.0.1:56140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T15:51:27.355972Z","caller":"traceutil/trace.go:171","msg":"trace[1007003726] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:231; }","duration":"163.687513ms","start":"2026-05-18T15:51:27.192259Z","end":"2026-05-18T15:51:27.355947Z","steps":["trace[1007003726] 'read index received' (duration: 91.26852ms)","trace[1007003726] 'applied index is now lower than readState.Index' (duration: 72.417973ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:51:27.356082Z","caller":"traceutil/trace.go:171","msg":"trace[2086197191] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"196.24254ms","start":"2026-05-18T15:51:27.159824Z","end":"2026-05-18T15:51:27.356066Z","steps":["trace[2086197191] 'process raft request' (duration: 123.697365ms)","trace[2086197191] 'compare' (duration: 72.25023ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:51:27.356167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.901878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:51:27.356197Z","caller":"traceutil/trace.go:171","msg":"trace[780351701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:227; }","duration":"163.964658ms","start":"2026-05-18T15:51:27.192224Z","end":"2026-05-18T15:51:27.356189Z","steps":["trace[780351701] 'agreement among raft nodes before linearized reading' (duration: 163.890207ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:51:27.356084Z","caller":"traceutil/trace.go:171","msg":"trace[1796572677] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"195.894623ms","start":"2026-05-18T15:51:27.160168Z","end":"2026-05-18T15:51:27.356063Z","steps":["trace[1796572677] 'process raft request' (duration: 195.73747ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:51:54.626386Z","caller":"traceutil/trace.go:171","msg":"trace[690743302] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"262.741277ms","start":"2026-05-18T15:51:54.363613Z","end":"2026-05-18T15:51:54.626355Z","steps":["trace[690743302] 'process raft request' (duration: 262.487262ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:51:54.630925Z","caller":"traceutil/trace.go:171","msg":"trace[1085878400] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"247.258155ms","start":"2026-05-18T15:51:54.383637Z","end":"2026-05-18T15:51:54.630895Z","steps":["trace[1085878400] 'process raft request' (duration: 246.96333ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:51:54.631676Z","caller":"traceutil/trace.go:171","msg":"trace[1497044181] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"124.086676ms","start":"2026-05-18T15:51:54.507546Z","end":"2026-05-18T15:51:54.631632Z","steps":["trace[1497044181] 'read index received' (duration: 119.297876ms)","trace[1497044181] 'applied index is now lower than readState.Index' (duration: 4.78808ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:51:54.631722Z","caller":"traceutil/trace.go:171","msg":"trace[2084232130] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"110.348937ms","start":"2026-05-18T15:51:54.521354Z","end":"2026-05-18T15:51:54.631703Z","steps":["trace[2084232130] 'process raft request' (duration: 109.627434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:54.631786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.230859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:51:54.631904Z","caller":"traceutil/trace.go:171","msg":"trace[1752515338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"124.395883ms","start":"2026-05-18T15:51:54.507495Z","end":"2026-05-18T15:51:54.631891Z","steps":["trace[1752515338] 'agreement among raft nodes before linearized reading' (duration: 124.248659ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:51:59.888719Z","caller":"traceutil/trace.go:171","msg":"trace[1044082925] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"476.790188ms","start":"2026-05-18T15:51:59.411897Z","end":"2026-05-18T15:51:59.888687Z","steps":["trace[1044082925] 'read index received' (duration: 476.044994ms)","trace[1044082925] 'applied index is now lower than readState.Index' (duration: 743.964µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:51:59.888867Z","caller":"traceutil/trace.go:171","msg":"trace[746933858] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"502.666775ms","start":"2026-05-18T15:51:59.386081Z","end":"2026-05-18T15:51:59.888747Z","steps":["trace[746933858] 'process raft request' (duration: 502.078554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:59.889021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.109525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pxfch\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-18T15:51:59.889082Z","caller":"traceutil/trace.go:171","msg":"trace[1037951758] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pxfch; range_end:; response_count:1; response_revision:613; }","duration":"477.179596ms","start":"2026-05-18T15:51:59.411883Z","end":"2026-05-18T15:51:59.889062Z","steps":["trace[1037951758] 'agreement among raft nodes before linearized reading' (duration: 477.064294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:59.889137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:51:59.411824Z","time spent":"477.301208ms","remote":"127.0.0.1:55974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-pxfch\" "} {"level":"warn","ts":"2026-05-18T15:51:59.88948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:51:59.386052Z","time spent":"502.88943ms","remote":"127.0.0.1:55878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:51:59.893399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.892313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:51:59.893482Z","caller":"traceutil/trace.go:171","msg":"trace[442615415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"389.012765ms","start":"2026-05-18T15:51:59.50445Z","end":"2026-05-18T15:51:59.893462Z","steps":["trace[442615415] 'agreement among raft nodes before linearized reading' (duration: 388.871823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:59.89351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.379499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T15:51:59.893577Z","caller":"traceutil/trace.go:171","msg":"trace[1004561837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"273.47692ms","start":"2026-05-18T15:51:59.620082Z","end":"2026-05-18T15:51:59.893559Z","steps":["trace[1004561837] 'agreement among raft nodes before linearized reading' (duration: 273.360048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:59.893623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.09749ms","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-05-18T15:51:59.893654Z","caller":"traceutil/trace.go:171","msg":"trace[1935315897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"310.200152ms","start":"2026-05-18T15:51:59.583446Z","end":"2026-05-18T15:51:59.893646Z","steps":["trace[1935315897] 'agreement among raft nodes before linearized reading' (duration: 310.154081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:51:59.893684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:51:59.583426Z","time spent":"310.252862ms","remote":"127.0.0.1:56072","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-05-18T15:51:59.893529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:51:59.504434Z","time spent":"389.086717ms","remote":"127.0.0.1:55754","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-05-18T15:51:59.893583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.183979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T15:51:59.89383Z","caller":"traceutil/trace.go:171","msg":"trace[435782952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"277.463795ms","start":"2026-05-18T15:51:59.616354Z","end":"2026-05-18T15:51:59.893818Z","steps":["trace[435782952] 'agreement among raft nodes before linearized reading' (duration: 277.19894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:05.977308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:06.478382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:06.978801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:07.479772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:07.506861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999925908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-18T15:52:07.506966Z","caller":"traceutil/trace.go:171","msg":"trace[1932148198] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000089312s","start":"2026-05-18T15:52:05.506857Z","end":"2026-05-18T15:52:07.506946Z","steps":["trace[1932148198] 'agreement among raft nodes before linearized reading' (duration: 1.999956959s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:07.507018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.506839Z","time spent":"2.000169484s","remote":"127.0.0.1:55756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T15:52:07.980792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:08.481182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:08.891011Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.430161252s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T15:52:08.891401Z","caller":"traceutil/trace.go:171","msg":"trace[1116735970] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"3.430621811s","start":"2026-05-18T15:52:05.460761Z","end":"2026-05-18T15:52:08.891383Z","steps":["trace[1116735970] 'process raft request' (duration: 3.430507308s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.891536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.460749Z","time spent":"3.430740713s","remote":"127.0.0.1:55862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T15:52:08.899562Z","caller":"traceutil/trace.go:171","msg":"trace[1730233337] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"3.425557125s","start":"2026-05-18T15:52:05.473981Z","end":"2026-05-18T15:52:08.899538Z","steps":["trace[1730233337] 'process raft request' (duration: 3.425343001s)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:08.899689Z","caller":"traceutil/trace.go:171","msg":"trace[104990748] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:729; }","duration":"3.42260685s","start":"2026-05-18T15:52:05.477065Z","end":"2026-05-18T15:52:08.899672Z","steps":["trace[104990748] 'read index received' (duration: 3.414222692s)","trace[104990748] 'applied index is now lower than readState.Index' (duration: 8.383198ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:08.899715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.473943Z","time spent":"3.425693727s","remote":"127.0.0.1:55974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18501,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:08.899827Z","caller":"traceutil/trace.go:171","msg":"trace[2111789229] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"2.311304112s","start":"2026-05-18T15:52:06.588491Z","end":"2026-05-18T15:52:08.899795Z","steps":["trace[2111789229] 'process raft request' (duration: 2.310956826s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.899951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.972393772s","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-05-18T15:52:08.899975Z","caller":"traceutil/trace.go:171","msg":"trace[816240437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"2.972511173s","start":"2026-05-18T15:52:05.927457Z","end":"2026-05-18T15:52:08.899968Z","steps":["trace[816240437] 'agreement among raft nodes before linearized reading' (duration: 2.972413651s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.899997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.927439Z","time spent":"2.972552304s","remote":"127.0.0.1:56072","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-05-18T15:52:08.899996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.960222402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T15:52:08.900551Z","caller":"traceutil/trace.go:171","msg":"trace[1461332208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"2.96119994s","start":"2026-05-18T15:52:05.939324Z","end":"2026-05-18T15:52:08.900524Z","steps":["trace[1461332208] 'agreement among raft nodes before linearized reading' (duration: 2.960537798s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.9006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.93929Z","time spent":"2.961287521s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T15:52:08.900691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403881975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-18T15:52:08.900746Z","caller":"traceutil/trace.go:171","msg":"trace[995870357] range","detail":"{range_begin:/registry/masterleases/199.204.45.233; range_end:; response_count:1; response_revision:705; }","duration":"1.403946506s","start":"2026-05-18T15:52:07.496784Z","end":"2026-05-18T15:52:08.90073Z","steps":["trace[995870357] 'agreement among raft nodes before linearized reading' (duration: 1.403228033s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.900795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.496738Z","time spent":"1.404048028s","remote":"127.0.0.1:55820","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.233\" "} {"level":"warn","ts":"2026-05-18T15:52:08.900869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.961013437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T15:52:08.900898Z","caller":"traceutil/trace.go:171","msg":"trace[1712634692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"2.961064408s","start":"2026-05-18T15:52:05.939824Z","end":"2026-05-18T15:52:08.900888Z","steps":["trace[1712634692] 'agreement among raft nodes before linearized reading' (duration: 2.960969206s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.900927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.939813Z","time spent":"2.961108689s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:08.90094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:06.588465Z","time spent":"2.311436245s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:52:08.901382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.42427153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-18T15:52:08.901507Z","caller":"traceutil/trace.go:171","msg":"trace[1224577159] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"3.424434163s","start":"2026-05-18T15:52:05.477028Z","end":"2026-05-18T15:52:08.901462Z","steps":["trace[1224577159] 'agreement among raft nodes before linearized reading' (duration: 3.422736681s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.901571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.477015Z","time spent":"3.424537245s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-18T15:52:08.901875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.223799425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-18T15:52:08.901946Z","caller":"traceutil/trace.go:171","msg":"trace[643450994] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"3.223875656s","start":"2026-05-18T15:52:05.678027Z","end":"2026-05-18T15:52:08.901903Z","steps":["trace[643450994] 'agreement among raft nodes before linearized reading' (duration: 3.221809448s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.901971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:05.67801Z","time spent":"3.223954168s","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-18T15:52:08.902547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.723640967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-18T15:52:08.902651Z","caller":"traceutil/trace.go:171","msg":"trace[1076808650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"1.72378674s","start":"2026-05-18T15:52:07.178844Z","end":"2026-05-18T15:52:08.902631Z","steps":["trace[1076808650] 'agreement among raft nodes before linearized reading' (duration: 1.723540926s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.902708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.178827Z","time spent":"1.723871012s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:08.902999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785825118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-18T15:52:08.903065Z","caller":"traceutil/trace.go:171","msg":"trace[631843219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"1.788878716s","start":"2026-05-18T15:52:07.114157Z","end":"2026-05-18T15:52:08.903035Z","steps":["trace[631843219] 'agreement among raft nodes before linearized reading' (duration: 1.785701456s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.903106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.114136Z","time spent":"1.788955407s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:08.907857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.399545525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:08.907923Z","caller":"traceutil/trace.go:171","msg":"trace[1614276170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"1.399651566s","start":"2026-05-18T15:52:07.508259Z","end":"2026-05-18T15:52:08.90791Z","steps":["trace[1614276170] 'agreement among raft nodes before linearized reading' (duration: 1.393491709s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:08.907985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:07.508241Z","time spent":"1.399735048s","remote":"127.0.0.1:55754","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-05-18T15:52:20.921014Z","caller":"traceutil/trace.go:171","msg":"trace[1708540292] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"105.833483ms","start":"2026-05-18T15:52:20.815153Z","end":"2026-05-18T15:52:20.920987Z","steps":["trace[1708540292] 'process raft request' (duration: 105.66821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:40.28468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:40.785833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4581742175530347699,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-18T15:52:41.240507Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.483348691s","expected-duration":"1s"} {"level":"info","ts":"2026-05-18T15:52:41.240838Z","caller":"traceutil/trace.go:171","msg":"trace[991839348] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"1.483774089s","start":"2026-05-18T15:52:39.757043Z","end":"2026-05-18T15:52:41.240817Z","steps":["trace[991839348] 'process raft request' (duration: 1.483640396s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.240978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.757017Z","time spent":"1.483891792s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.415059Z","caller":"traceutil/trace.go:171","msg":"trace[41379515] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.342560251s","start":"2026-05-18T15:52:40.072474Z","end":"2026-05-18T15:52:41.415034Z","steps":["trace[41379515] 'process raft request' (duration: 1.270177368s)","trace[41379515] 'compare' (duration: 72.2014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:41.415209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:40.072444Z","time spent":"1.342684624s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T15:52:41.415327Z","caller":"traceutil/trace.go:171","msg":"trace[1401508272] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"736.415327ms","start":"2026-05-18T15:52:40.678893Z","end":"2026-05-18T15:52:41.415308Z","steps":["trace[1401508272] 'process raft request' (duration: 736.098762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.415493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.63136761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-18T15:52:41.415409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:40.678867Z","time spent":"736.500789ms","remote":"127.0.0.1:56072","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-05-18T15:52:41.415578Z","caller":"traceutil/trace.go:171","msg":"trace[1904308651] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:945; }","duration":"1.631526993s","start":"2026-05-18T15:52:39.78403Z","end":"2026-05-18T15:52:41.415557Z","steps":["trace[1904308651] 'agreement among raft nodes before linearized reading' (duration: 1.63136783s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.415625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.784007Z","time spent":"1.631607674s","remote":"127.0.0.1:55986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-18T15:52:41.415829Z","caller":"traceutil/trace.go:171","msg":"trace[208916773] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:979; }","duration":"1.631169126s","start":"2026-05-18T15:52:39.784088Z","end":"2026-05-18T15:52:41.415257Z","steps":["trace[208916773] 'read index received' (duration: 1.45668506s)","trace[208916773] 'applied index is now lower than readState.Index' (duration: 174.482686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:41.416083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474999425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T15:52:41.416126Z","caller":"traceutil/trace.go:171","msg":"trace[1648178693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:945; }","duration":"1.475093857s","start":"2026-05-18T15:52:39.94102Z","end":"2026-05-18T15:52:41.416114Z","steps":["trace[1648178693] 'agreement among raft nodes before linearized reading' (duration: 1.474929514s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.416131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468386689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-18T15:52:41.416161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.940989Z","time spent":"1.475164008s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-18T15:52:41.416174Z","caller":"traceutil/trace.go:171","msg":"trace[649686637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.468459991s","start":"2026-05-18T15:52:39.947703Z","end":"2026-05-18T15:52:41.416163Z","steps":["trace[649686637] 'agreement among raft nodes before linearized reading' (duration: 1.468329199s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.41609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466460633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T15:52:41.416276Z","caller":"traceutil/trace.go:171","msg":"trace[286707205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.466679627s","start":"2026-05-18T15:52:39.949582Z","end":"2026-05-18T15:52:41.416262Z","steps":["trace[286707205] 'agreement among raft nodes before linearized reading' (duration: 1.466369051s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.416306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.269354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-18T15:52:41.416325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.470288786s","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-05-18T15:52:41.416345Z","caller":"traceutil/trace.go:171","msg":"trace[2109604663] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:945; }","duration":"899.348195ms","start":"2026-05-18T15:52:40.516987Z","end":"2026-05-18T15:52:41.416335Z","steps":["trace[2109604663] 'agreement among raft nodes before linearized reading' (duration: 899.279974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.416378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:40.516966Z","time spent":"899.403916ms","remote":"127.0.0.1:55896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-05-18T15:52:41.416376Z","caller":"traceutil/trace.go:171","msg":"trace[1025248342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"1.470355057s","start":"2026-05-18T15:52:39.946009Z","end":"2026-05-18T15:52:41.416364Z","steps":["trace[1025248342] 'agreement among raft nodes before linearized reading' (duration: 1.470207734s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.416414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.946003Z","time spent":"1.470404658s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:41.416428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.949572Z","time spent":"1.466752239s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:41.416208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.947685Z","time spent":"1.468513472s","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:41.416765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46895247s","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-05-18T15:52:41.416807Z","caller":"traceutil/trace.go:171","msg":"trace[1364455676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"1.469037592s","start":"2026-05-18T15:52:39.94776Z","end":"2026-05-18T15:52:41.416797Z","steps":["trace[1364455676] 'agreement among raft nodes before linearized reading' (duration: 1.468282258s)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.41684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:39.947739Z","time spent":"1.469090833s","remote":"127.0.0.1:56072","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-05-18T15:52:41.664238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.81078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:52:41.664678Z","caller":"traceutil/trace.go:171","msg":"trace[1657889185] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"243.276701ms","start":"2026-05-18T15:52:41.421384Z","end":"2026-05-18T15:52:41.664661Z","steps":["trace[1657889185] 'process raft request' (duration: 243.2079ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.6647Z","caller":"traceutil/trace.go:171","msg":"trace[308706582] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"243.313392ms","start":"2026-05-18T15:52:41.421365Z","end":"2026-05-18T15:52:41.664679Z","steps":["trace[308706582] 'process raft request' (duration: 243.163859ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.66491Z","caller":"traceutil/trace.go:171","msg":"trace[475016572] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"243.466014ms","start":"2026-05-18T15:52:41.421158Z","end":"2026-05-18T15:52:41.664624Z","steps":["trace[475016572] 'process raft request' (duration: 109.180365ms)","trace[475016572] 'compare' (duration: 133.669878ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:41.664956Z","caller":"traceutil/trace.go:171","msg":"trace[2128149782] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"243.472945ms","start":"2026-05-18T15:52:41.421463Z","end":"2026-05-18T15:52:41.664936Z","steps":["trace[2128149782] 'process raft request' (duration: 243.162089ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:41.664953Z","caller":"traceutil/trace.go:171","msg":"trace[92855139] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:982; }","duration":"158.134688ms","start":"2026-05-18T15:52:41.506798Z","end":"2026-05-18T15:52:41.664933Z","steps":["trace[92855139] 'read index received' (duration: 23.551324ms)","trace[92855139] 'applied index is now lower than readState.Index' (duration: 134.581834ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:41.665049Z","caller":"traceutil/trace.go:171","msg":"trace[511414521] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"242.860654ms","start":"2026-05-18T15:52:41.422166Z","end":"2026-05-18T15:52:41.665026Z","steps":["trace[511414521] 'process raft request' (duration: 242.66135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:41.665062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.25526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:41.665189Z","caller":"traceutil/trace.go:171","msg":"trace[867590612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"158.422193ms","start":"2026-05-18T15:52:41.506753Z","end":"2026-05-18T15:52:41.665175Z","steps":["trace[867590612] 'agreement among raft nodes before linearized reading' (duration: 158.25524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:42.947324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.248944ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-18T15:52:43.810264Z","caller":"traceutil/trace.go:171","msg":"trace[425763159] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"136.131884ms","start":"2026-05-18T15:52:43.674104Z","end":"2026-05-18T15:52:43.810235Z","steps":["trace[425763159] 'process raft request' (duration: 97.756171ms)","trace[425763159] 'compare' (duration: 38.20956ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:43.810567Z","caller":"traceutil/trace.go:171","msg":"trace[1717489663] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"136.313157ms","start":"2026-05-18T15:52:43.674243Z","end":"2026-05-18T15:52:43.810556Z","steps":["trace[1717489663] 'process raft request' (duration: 135.954251ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:43.810906Z","caller":"traceutil/trace.go:171","msg":"trace[634071388] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"135.974731ms","start":"2026-05-18T15:52:43.674904Z","end":"2026-05-18T15:52:43.810879Z","steps":["trace[634071388] 'process raft request' (duration: 135.88647ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:43.810926Z","caller":"traceutil/trace.go:171","msg":"trace[425368758] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"136.637354ms","start":"2026-05-18T15:52:43.674262Z","end":"2026-05-18T15:52:43.8109Z","steps":["trace[425368758] 'process raft request' (duration: 136.193345ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:45.873215Z","caller":"traceutil/trace.go:171","msg":"trace[322384137] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"121.538282ms","start":"2026-05-18T15:52:45.751645Z","end":"2026-05-18T15:52:45.873183Z","steps":["trace[322384137] 'process raft request' (duration: 121.349169ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:46.016478Z","caller":"traceutil/trace.go:171","msg":"trace[1364315140] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"138.094446ms","start":"2026-05-18T15:52:45.878352Z","end":"2026-05-18T15:52:46.016446Z","steps":["trace[1364315140] 'process raft request' (duration: 104.490839ms)","trace[1364315140] 'compare' (duration: 33.403884ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:46.016799Z","caller":"traceutil/trace.go:171","msg":"trace[1939191613] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"138.335481ms","start":"2026-05-18T15:52:45.878441Z","end":"2026-05-18T15:52:46.016777Z","steps":["trace[1939191613] 'process raft request' (duration: 137.953033ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:46.017011Z","caller":"traceutil/trace.go:171","msg":"trace[1028138983] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"138.488513ms","start":"2026-05-18T15:52:45.87849Z","end":"2026-05-18T15:52:46.016979Z","steps":["trace[1028138983] 'process raft request' (duration: 138.175537ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:47.997228Z","caller":"traceutil/trace.go:171","msg":"trace[1563196155] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"120.237678ms","start":"2026-05-18T15:52:47.876965Z","end":"2026-05-18T15:52:47.997203Z","steps":["trace[1563196155] 'read index received' (duration: 38.045961ms)","trace[1563196155] 'applied index is now lower than readState.Index' (duration: 82.190697ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:47.997279Z","caller":"traceutil/trace.go:171","msg":"trace[1698636740] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"187.914501ms","start":"2026-05-18T15:52:47.809345Z","end":"2026-05-18T15:52:47.997259Z","steps":["trace[1698636740] 'process raft request' (duration: 105.760014ms)","trace[1698636740] 'compare' (duration: 81.973993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:47.997517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.541784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-18T15:52:47.997594Z","caller":"traceutil/trace.go:171","msg":"trace[938872594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"120.666646ms","start":"2026-05-18T15:52:47.876909Z","end":"2026-05-18T15:52:47.997576Z","steps":["trace[938872594] 'agreement among raft nodes before linearized reading' (duration: 120.437732ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:48.321496Z","caller":"traceutil/trace.go:171","msg":"trace[1567085425] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"319.216887ms","start":"2026-05-18T15:52:48.002248Z","end":"2026-05-18T15:52:48.321465Z","steps":["trace[1567085425] 'process raft request' (duration: 319.007453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:48.321753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.002218Z","time spent":"319.442571ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:52:48.496898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.535424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:52:48.497043Z","caller":"traceutil/trace.go:171","msg":"trace[2097986552] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1003; }","duration":"493.162732ms","start":"2026-05-18T15:52:48.003823Z","end":"2026-05-18T15:52:48.496986Z","steps":["trace[2097986552] 'read index received' (duration: 317.403852ms)","trace[2097986552] 'applied index is now lower than readState.Index' (duration: 175.75762ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:52:48.497055Z","caller":"traceutil/trace.go:171","msg":"trace[394118276] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"494.447647ms","start":"2026-05-18T15:52:48.002584Z","end":"2026-05-18T15:52:48.497031Z","steps":["trace[394118276] 'process raft request' (duration: 373.485975ms)","trace[394118276] 'compare' (duration: 120.3969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:48.497174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.002566Z","time spent":"494.55923ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:52:48.49716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.329856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-18T15:52:48.497325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.398735ms","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-05-18T15:52:48.497396Z","caller":"traceutil/trace.go:171","msg":"trace[1297204664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"476.493836ms","start":"2026-05-18T15:52:48.020889Z","end":"2026-05-18T15:52:48.497383Z","steps":["trace[1297204664] 'agreement among raft nodes before linearized reading' (duration: 476.281402ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:48.497426Z","caller":"traceutil/trace.go:171","msg":"trace[18583962] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:966; }","duration":"493.528669ms","start":"2026-05-18T15:52:48.003786Z","end":"2026-05-18T15:52:48.497314Z","steps":["trace[18583962] 'agreement among raft nodes before linearized reading' (duration: 493.303565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:48.497486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.020877Z","time spent":"476.575739ms","remote":"127.0.0.1:56072","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-05-18T15:52:48.497545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.602109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T15:52:48.497594Z","caller":"traceutil/trace.go:171","msg":"trace[1616449044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"476.69352ms","start":"2026-05-18T15:52:48.020886Z","end":"2026-05-18T15:52:48.49758Z","steps":["trace[1616449044] 'agreement among raft nodes before linearized reading' (duration: 476.562708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:48.497641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.020862Z","time spent":"476.769362ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T15:52:48.4977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.003773Z","time spent":"493.752583ms","remote":"127.0.0.1:56074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-18T15:52:48.497685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.282953ms","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-05-18T15:52:48.498095Z","caller":"traceutil/trace.go:171","msg":"trace[551288676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"476.522998ms","start":"2026-05-18T15:52:48.021363Z","end":"2026-05-18T15:52:48.497886Z","steps":["trace[551288676] 'agreement among raft nodes before linearized reading' (duration: 475.969627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:48.49815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.021353Z","time spent":"476.783542ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T15:52:48.959153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.842337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:52:48.959388Z","caller":"traceutil/trace.go:171","msg":"trace[628116883] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1005; }","duration":"451.952763ms","start":"2026-05-18T15:52:48.507418Z","end":"2026-05-18T15:52:48.959371Z","steps":["trace[628116883] 'read index received' (duration: 78.757243ms)","trace[628116883] 'applied index is now lower than readState.Index' (duration: 373.19459ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:48.959551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.127186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:52:48.959591Z","caller":"traceutil/trace.go:171","msg":"trace[1548142953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"452.187398ms","start":"2026-05-18T15:52:48.507391Z","end":"2026-05-18T15:52:48.959579Z","steps":["trace[1548142953] 'agreement among raft nodes before linearized reading' (duration: 452.035695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:52:48.959625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.507383Z","time spent":"452.234298ms","remote":"127.0.0.1:55756","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-05-18T15:52:48.95966Z","caller":"traceutil/trace.go:171","msg":"trace[1942287440] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"453.779707ms","start":"2026-05-18T15:52:48.505795Z","end":"2026-05-18T15:52:48.959575Z","steps":["trace[1942287440] 'process raft request' (duration: 453.484431ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:52:48.960346Z","caller":"traceutil/trace.go:171","msg":"trace[1413187269] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"456.360426ms","start":"2026-05-18T15:52:48.503437Z","end":"2026-05-18T15:52:48.959797Z","steps":["trace[1413187269] 'process raft request' (duration: 170.787415ms)","trace[1413187269] 'compare' (duration: 284.574082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:52:48.96058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.503407Z","time spent":"457.014078ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:52:48.960717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:52:48.505781Z","time spent":"454.455879ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:53:01.322678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.687618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:53:01.322764Z","caller":"traceutil/trace.go:171","msg":"trace[1659331029] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"160.585762ms","start":"2026-05-18T15:53:01.162162Z","end":"2026-05-18T15:53:01.322748Z","steps":["trace[1659331029] 'read index received' (duration: 31.717871ms)","trace[1659331029] 'applied index is now lower than readState.Index' (duration: 128.867161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:53:01.322823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.665573ms","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-05-18T15:53:01.322856Z","caller":"traceutil/trace.go:171","msg":"trace[1758961023] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1012; }","duration":"160.733535ms","start":"2026-05-18T15:53:01.162114Z","end":"2026-05-18T15:53:01.322847Z","steps":["trace[1758961023] 'agreement among raft nodes before linearized reading' (duration: 160.675184ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:53:01.322854Z","caller":"traceutil/trace.go:171","msg":"trace[1723927701] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"307.798651ms","start":"2026-05-18T15:53:01.01503Z","end":"2026-05-18T15:53:01.322828Z","steps":["trace[1723927701] 'process raft request' (duration: 178.899439ms)","trace[1723927701] 'compare' (duration: 128.563746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T15:53:01.322985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:53:01.015013Z","time spent":"307.895763ms","remote":"127.0.0.1:56072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T15:53:28.151318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.318096ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-18T15:53:28.151447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T15:53:27.791601Z","time spent":"359.842137ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-18T15:53:28.908475Z","caller":"traceutil/trace.go:171","msg":"trace[1516433254] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1149; }","duration":"140.146084ms","start":"2026-05-18T15:53:28.768303Z","end":"2026-05-18T15:53:28.908449Z","steps":["trace[1516433254] 'read index received' (duration: 140.004262ms)","trace[1516433254] 'applied index is now lower than readState.Index' (duration: 141.112µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:53:28.908547Z","caller":"traceutil/trace.go:171","msg":"trace[1004122416] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"154.814993ms","start":"2026-05-18T15:53:28.753722Z","end":"2026-05-18T15:53:28.908537Z","steps":["trace[1004122416] 'process raft request' (duration: 154.627569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:53:28.908702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.38555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-18T15:53:28.908753Z","caller":"traceutil/trace.go:171","msg":"trace[213593019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"140.472821ms","start":"2026-05-18T15:53:28.768267Z","end":"2026-05-18T15:53:28.90874Z","steps":["trace[213593019] 'agreement among raft nodes before linearized reading' (duration: 140.322858ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:54:03.693188Z","caller":"traceutil/trace.go:171","msg":"trace[852506286] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"252.719774ms","start":"2026-05-18T15:54:03.440448Z","end":"2026-05-18T15:54:03.693168Z","steps":["trace[852506286] 'read index received' (duration: 252.578562ms)","trace[852506286] 'applied index is now lower than readState.Index' (duration: 140.372µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:54:03.693305Z","caller":"traceutil/trace.go:171","msg":"trace[519326630] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"254.091969ms","start":"2026-05-18T15:54:03.439193Z","end":"2026-05-18T15:54:03.693285Z","steps":["trace[519326630] 'process raft request' (duration: 253.822645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:54:03.693584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.117413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T15:54:03.69362Z","caller":"traceutil/trace.go:171","msg":"trace[1098610513] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1277; }","duration":"253.181343ms","start":"2026-05-18T15:54:03.44043Z","end":"2026-05-18T15:54:03.693611Z","steps":["trace[1098610513] 'agreement among raft nodes before linearized reading' (duration: 253.117682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T15:54:03.900118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.365117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T15:54:03.900253Z","caller":"traceutil/trace.go:171","msg":"trace[361358113] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"202.490473ms","start":"2026-05-18T15:54:03.697724Z","end":"2026-05-18T15:54:03.900215Z","steps":["trace[361358113] 'process raft request' (duration: 58.917742ms)","trace[361358113] 'compare' (duration: 143.205585ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T15:54:04.822715Z","caller":"traceutil/trace.go:171","msg":"trace[1756823982] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"113.058446ms","start":"2026-05-18T15:54:04.709629Z","end":"2026-05-18T15:54:04.822688Z","steps":["trace[1756823982] 'process raft request' (duration: 112.897883ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T15:54:16.019788Z","caller":"traceutil/trace.go:171","msg":"trace[1079001701] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"240.828054ms","start":"2026-05-18T15:54:15.778921Z","end":"2026-05-18T15:54:16.019749Z","steps":["trace[1079001701] 'process raft request' (duration: 240.61421ms)"],"step_count":1}