{"level":"warn","ts":"2026-06-15T19:00:04.85684Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T19:00:04.85692Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.163: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.19.213.163:2380","--initial-cluster=instance=https://199.19.213.163:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.163:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.163: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-06-15T19:00:04.856988Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-15T19:00:04.856995Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-06-15T19:00:04.857012Z","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-06-15T19:00:04.857599Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"]} {"level":"info","ts":"2026-06-15T19:00:04.857665Z","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.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.163: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-06-15T19:00:04.863065Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.708871ms"} {"level":"info","ts":"2026-06-15T19:00:04.872579Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7941a908d261618","cluster-id":"5b6cb15db6ea8c10"} {"level":"info","ts":"2026-06-15T19:00:04.872662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T19:00:04.87269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 0"} {"level":"info","ts":"2026-06-15T19:00:04.872754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7941a908d261618 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T19:00:04.872778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 1"} {"level":"info","ts":"2026-06-15T19:00:04.872846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"warn","ts":"2026-06-15T19:00:04.878974Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T19:00:04.880426Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T19:00:04.881291Z","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-06-15T19:00:04.883045Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7941a908d261618","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T19:00:04.883207Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7941a908d261618","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T19:00:04.883316Z","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-06-15T19:00:04.883376Z","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-06-15T19:00:04.883384Z","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-06-15T19:00:04.887464Z","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-06-15T19:00:04.887554Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-06-15T19:00:04.887672Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-06-15T19:00:04.888028Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7941a908d261618","initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T19:00:04.888058Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T19:00:04.89396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"info","ts":"2026-06-15T19:00:04.894085Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","added-peer-id":"e7941a908d261618","added-peer-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-06-15T19:00:05.773302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T19:00:05.773389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T19:00:05.773413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgPreVoteResp from e7941a908d261618 at term 1"} {"level":"info","ts":"2026-06-15T19:00:05.773434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became candidate at term 2"} {"level":"info","ts":"2026-06-15T19:00:05.773445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgVoteResp from e7941a908d261618 at term 2"} {"level":"info","ts":"2026-06-15T19:00:05.773461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became leader at term 2"} {"level":"info","ts":"2026-06-15T19:00:05.773476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7941a908d261618 elected leader e7941a908d261618 at term 2"} {"level":"info","ts":"2026-06-15T19:00:05.774534Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7941a908d261618","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.163:2379]}","request-path":"/0/members/e7941a908d261618/attributes","cluster-id":"5b6cb15db6ea8c10","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T19:00:05.774543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:00:05.774541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T19:00:05.774616Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T19:00:05.774909Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T19:00:05.774983Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T19:00:05.775525Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:00:05.775737Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:00:05.775887Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T19:00:05.779724Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T19:00:05.780005Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.163:2379"} {"level":"info","ts":"2026-06-15T19:00:08.267881Z","caller":"traceutil/trace.go:171","msg":"trace[955584331] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:122; }","duration":"130.562543ms","start":"2026-06-15T19:00:08.137289Z","end":"2026-06-15T19:00:08.267852Z","steps":["trace[955584331] 'read index received' (duration: 71.60252ms)","trace[955584331] 'applied index is now lower than readState.Index' (duration: 58.957913ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:00:08.267933Z","caller":"traceutil/trace.go:171","msg":"trace[618020797] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"142.949634ms","start":"2026-06-15T19:00:08.124947Z","end":"2026-06-15T19:00:08.267897Z","steps":["trace[618020797] 'process raft request' (duration: 83.972831ms)","trace[618020797] 'compare' (duration: 58.746736ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:08.268094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.780821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-15T19:00:08.268162Z","caller":"traceutil/trace.go:171","msg":"trace[1033630045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"130.884793ms","start":"2026-06-15T19:00:08.137266Z","end":"2026-06-15T19:00:08.268151Z","steps":["trace[1033630045] 'agreement among raft nodes before linearized reading' (duration: 130.726938ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:08.626671Z","caller":"traceutil/trace.go:171","msg":"trace[1745754886] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"155.014165ms","start":"2026-06-15T19:00:08.471626Z","end":"2026-06-15T19:00:08.62664Z","steps":["trace[1745754886] 'process raft request' (duration: 85.619712ms)","trace[1745754886] 'compare' (duration: 69.26592ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:00:10.316415Z","caller":"traceutil/trace.go:171","msg":"trace[214073477] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"114.631167ms","start":"2026-06-15T19:00:10.201758Z","end":"2026-06-15T19:00:10.316389Z","steps":["trace[214073477] 'process raft request' (duration: 87.386365ms)","trace[214073477] 'compare' (duration: 27.097778ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:29.039679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197070100007865,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T19:00:29.277611Z","caller":"traceutil/trace.go:171","msg":"trace[771482236] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"738.741274ms","start":"2026-06-15T19:00:28.538831Z","end":"2026-06-15T19:00:29.277572Z","steps":["trace[771482236] 'read index received' (duration: 738.337973ms)","trace[771482236] 'applied index is now lower than readState.Index' (duration: 401.751µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:00:29.277687Z","caller":"traceutil/trace.go:171","msg":"trace[821661719] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"785.967941ms","start":"2026-06-15T19:00:28.491687Z","end":"2026-06-15T19:00:29.277654Z","steps":["trace[821661719] 'process raft request' (duration: 785.647263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:29.277873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.621039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T19:00:29.278019Z","caller":"traceutil/trace.go:171","msg":"trace[485214166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"736.835035ms","start":"2026-06-15T19:00:28.541169Z","end":"2026-06-15T19:00:29.278005Z","steps":["trace[485214166] 'agreement among raft nodes before linearized reading' (duration: 736.602849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:29.277994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.123281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:00:29.27805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.858756ms","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-06-15T19:00:29.278069Z","caller":"traceutil/trace.go:171","msg":"trace[795248371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"364.263573ms","start":"2026-06-15T19:00:28.913788Z","end":"2026-06-15T19:00:29.278052Z","steps":["trace[795248371] 'agreement among raft nodes before linearized reading' (duration: 364.086608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:29.278135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:28.91377Z","time spent":"364.357696ms","remote":"127.0.0.1:37030","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-06-15T19:00:29.278063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:28.541148Z","time spent":"736.903697ms","remote":"127.0.0.1:37344","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":"info","ts":"2026-06-15T19:00:29.278169Z","caller":"traceutil/trace.go:171","msg":"trace[1467707865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:420; }","duration":"609.945227ms","start":"2026-06-15T19:00:28.668148Z","end":"2026-06-15T19:00:29.278093Z","steps":["trace[1467707865] 'agreement among raft nodes before linearized reading' (duration: 609.783474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:29.278246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:28.66813Z","time spent":"610.104912ms","remote":"127.0.0.1:37344","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-06-15T19:00:29.278306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:28.491665Z","time spent":"786.083523ms","remote":"127.0.0.1:37240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:00:29.278434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.597605ms","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-06-15T19:00:29.278644Z","caller":"traceutil/trace.go:171","msg":"trace[561886262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"739.842493ms","start":"2026-06-15T19:00:28.538792Z","end":"2026-06-15T19:00:29.278635Z","steps":["trace[561886262] 'agreement among raft nodes before linearized reading' (duration: 738.97303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:29.278675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:28.538773Z","time spent":"739.894274ms","remote":"127.0.0.1:37344","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":"info","ts":"2026-06-15T19:00:33.863175Z","caller":"traceutil/trace.go:171","msg":"trace[119982846] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"162.209221ms","start":"2026-06-15T19:00:33.700938Z","end":"2026-06-15T19:00:33.863148Z","steps":["trace[119982846] 'process raft request' (duration: 162.054358ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:49.295808Z","caller":"traceutil/trace.go:171","msg":"trace[16040274] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"152.61471ms","start":"2026-06-15T19:00:49.143171Z","end":"2026-06-15T19:00:49.295785Z","steps":["trace[16040274] 'process raft request' (duration: 152.1893ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:52.038295Z","caller":"traceutil/trace.go:171","msg":"trace[1164593619] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"125.4122ms","start":"2026-06-15T19:00:51.912856Z","end":"2026-06-15T19:00:52.038269Z","steps":["trace[1164593619] 'read index received' (duration: 125.326758ms)","trace[1164593619] 'applied index is now lower than readState.Index' (duration: 84.642µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:52.038436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.547772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:00:52.038461Z","caller":"traceutil/trace.go:171","msg":"trace[1106121343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"125.602203ms","start":"2026-06-15T19:00:51.91285Z","end":"2026-06-15T19:00:52.038452Z","steps":["trace[1106121343] 'agreement among raft nodes before linearized reading' (duration: 125.505311ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:52.038435Z","caller":"traceutil/trace.go:171","msg":"trace[1483511649] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"395.071876ms","start":"2026-06-15T19:00:51.643333Z","end":"2026-06-15T19:00:52.038405Z","steps":["trace[1483511649] 'process raft request' (duration: 394.75723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:52.038573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:51.643314Z","time spent":"395.182499ms","remote":"127.0.0.1:37244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:00:52.541113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197070100008251,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T19:00:52.766225Z","caller":"traceutil/trace.go:171","msg":"trace[558317460] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"847.146296ms","start":"2026-06-15T19:00:51.919059Z","end":"2026-06-15T19:00:52.766205Z","steps":["trace[558317460] 'process raft request' (duration: 846.688676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:52.766336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:51.919031Z","time spent":"847.242717ms","remote":"127.0.0.1:37158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:00:53.401626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.953455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:00:53.40187Z","caller":"traceutil/trace.go:171","msg":"trace[1296594075] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"576.612761ms","start":"2026-06-15T19:00:52.825243Z","end":"2026-06-15T19:00:53.401856Z","steps":["trace[1296594075] 'process raft request' (duration: 576.541589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:53.402145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:52.825227Z","time spent":"576.897136ms","remote":"127.0.0.1:37630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T19:00:53.401932Z","caller":"traceutil/trace.go:171","msg":"trace[299572451] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"1.362126749s","start":"2026-06-15T19:00:52.039793Z","end":"2026-06-15T19:00:53.40192Z","steps":["trace[299572451] 'read index received' (duration: 725.900427ms)","trace[299572451] 'applied index is now lower than readState.Index' (duration: 636.224842ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:53.401986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36218973s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:00:53.40251Z","caller":"traceutil/trace.go:171","msg":"trace[660400910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"1.362693051s","start":"2026-06-15T19:00:52.039765Z","end":"2026-06-15T19:00:53.402458Z","steps":["trace[660400910] 'agreement among raft nodes before linearized reading' (duration: 1.36218417s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:53.402666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:52.03975Z","time spent":"1.362856885s","remote":"127.0.0.1:37044","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-06-15T19:00:53.402009Z","caller":"traceutil/trace.go:171","msg":"trace[1714086375] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.274246249s","start":"2026-06-15T19:00:52.127756Z","end":"2026-06-15T19:00:53.402002Z","steps":["trace[1714086375] 'process raft request' (duration: 1.003705991s)","trace[1714086375] 'compare' (duration: 269.657589ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:53.402786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:52.127734Z","time spent":"1.275012165s","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:00:53.402877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.354436252s","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-06-15T19:00:53.403016Z","caller":"traceutil/trace.go:171","msg":"trace[2079754635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"1.354537694s","start":"2026-06-15T19:00:52.048433Z","end":"2026-06-15T19:00:53.40297Z","steps":["trace[2079754635] 'agreement among raft nodes before linearized reading' (duration: 1.354209998s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:53.403134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:52.048386Z","time spent":"1.35470649s","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T19:00:54.685677Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.23194134s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-15T19:00:55.411509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197070100008263,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T19:00:55.596695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.642105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T19:00:55.597029Z","caller":"traceutil/trace.go:171","msg":"trace[297726221] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"2.148471981s","start":"2026-06-15T19:00:53.448509Z","end":"2026-06-15T19:00:55.596981Z","steps":["trace[297726221] 'process raft request' (duration: 1.237469499s)","trace[297726221] 'compare' (duration: 910.374159ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:55.59723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:53.44849Z","time spent":"2.148654105s","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:00:55.59725Z","caller":"traceutil/trace.go:171","msg":"trace[421544587] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.145339874s","start":"2026-06-15T19:00:53.451871Z","end":"2026-06-15T19:00:55.597211Z","steps":["trace[421544587] 'process raft request' (duration: 2.144903464s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.597991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:53.451843Z","time spent":"2.145559699s","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:00:55.598509Z","caller":"traceutil/trace.go:171","msg":"trace[1595313451] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.757226156s","start":"2026-06-15T19:00:53.841194Z","end":"2026-06-15T19:00:55.59842Z","steps":["trace[1595313451] 'process raft request' (duration: 1.756088832s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.598922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:53.841161Z","time spent":"1.757717426s","remote":"127.0.0.1:37158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:00:55.613723Z","caller":"traceutil/trace.go:171","msg":"trace[1154585895] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"703.022908ms","start":"2026-06-15T19:00:54.910676Z","end":"2026-06-15T19:00:55.613699Z","steps":["trace[1154585895] 'read index received' (duration: 678.862319ms)","trace[1154585895] 'applied index is now lower than readState.Index' (duration: 24.158189ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:55.614145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.379096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:00:55.614231Z","caller":"traceutil/trace.go:171","msg":"trace[1582221582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"703.57294ms","start":"2026-06-15T19:00:54.91063Z","end":"2026-06-15T19:00:55.614203Z","steps":["trace[1582221582] 'agreement among raft nodes before linearized reading' (duration: 703.336074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.614343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:54.910612Z","time spent":"703.672311ms","remote":"127.0.0.1:37030","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-06-15T19:00:55.614387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.665161ms","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-06-15T19:00:55.614488Z","caller":"traceutil/trace.go:171","msg":"trace[1391321776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"165.820374ms","start":"2026-06-15T19:00:55.448642Z","end":"2026-06-15T19:00:55.614463Z","steps":["trace[1391321776] 'agreement among raft nodes before linearized reading' (duration: 165.480417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:55.614654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.837107ms","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-06-15T19:00:55.614839Z","caller":"traceutil/trace.go:171","msg":"trace[1008502328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"178.269317ms","start":"2026-06-15T19:00:55.436544Z","end":"2026-06-15T19:00:55.614813Z","steps":["trace[1008502328] 'agreement among raft nodes before linearized reading' (duration: 177.4868ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:00:57.298875Z","caller":"traceutil/trace.go:171","msg":"trace[1225651919] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"385.128213ms","start":"2026-06-15T19:00:56.913711Z","end":"2026-06-15T19:00:57.298839Z","steps":["trace[1225651919] 'read index received' (duration: 384.380827ms)","trace[1225651919] 'applied index is now lower than readState.Index' (duration: 746.366µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:00:57.299058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.313856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:00:57.299093Z","caller":"traceutil/trace.go:171","msg":"trace[1833575004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"385.419638ms","start":"2026-06-15T19:00:56.913662Z","end":"2026-06-15T19:00:57.299081Z","steps":["trace[1833575004] 'agreement among raft nodes before linearized reading' (duration: 385.292766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:57.299131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:56.913643Z","time spent":"385.4802ms","remote":"127.0.0.1:37030","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-06-15T19:00:57.299213Z","caller":"traceutil/trace.go:171","msg":"trace[109886681] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"516.486002ms","start":"2026-06-15T19:00:56.782664Z","end":"2026-06-15T19:00:57.29915Z","steps":["trace[109886681] 'process raft request' (duration: 515.48571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:00:57.299371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:00:56.782648Z","time spent":"516.658765ms","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T19:01:00.016547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.312713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-06-15T19:01:00.016635Z","caller":"traceutil/trace.go:171","msg":"trace[158016463] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"109.440295ms","start":"2026-06-15T19:00:59.90718Z","end":"2026-06-15T19:01:00.016621Z","steps":["trace[158016463] 'range keys from in-memory index tree' (duration: 109.182501ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:09.293667Z","caller":"traceutil/trace.go:171","msg":"trace[962203104] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"378.927294ms","start":"2026-06-15T19:01:08.914707Z","end":"2026-06-15T19:01:09.293635Z","steps":["trace[962203104] 'read index received' (duration: 378.66386ms)","trace[962203104] 'applied index is now lower than readState.Index' (duration: 262.184µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:01:09.293847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.122938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:09.293878Z","caller":"traceutil/trace.go:171","msg":"trace[1909428892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"379.23521ms","start":"2026-06-15T19:01:08.914635Z","end":"2026-06-15T19:01:09.29387Z","steps":["trace[1909428892] 'agreement among raft nodes before linearized reading' (duration: 379.124969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:09.293907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:08.914605Z","time spent":"379.296262ms","remote":"127.0.0.1:37030","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-06-15T19:01:09.294171Z","caller":"traceutil/trace.go:171","msg":"trace[1449799466] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"612.225009ms","start":"2026-06-15T19:01:08.68191Z","end":"2026-06-15T19:01:09.294135Z","steps":["trace[1449799466] 'process raft request' (duration: 611.544146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:09.294341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:08.681883Z","time spent":"612.370482ms","remote":"127.0.0.1:37344","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-06-15T19:01:09.578497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.316125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T19:01:09.578601Z","caller":"traceutil/trace.go:171","msg":"trace[160427391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"209.491409ms","start":"2026-06-15T19:01:09.369085Z","end":"2026-06-15T19:01:09.578576Z","steps":["trace[160427391] 'range keys from in-memory index tree' (duration: 208.950649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:13.416255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197070100008597,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T19:01:13.57287Z","caller":"traceutil/trace.go:171","msg":"trace[1021462042] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"808.076076ms","start":"2026-06-15T19:01:12.764777Z","end":"2026-06-15T19:01:13.572853Z","steps":["trace[1021462042] 'process raft request' (duration: 807.907732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:13.573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:12.764748Z","time spent":"808.177187ms","remote":"127.0.0.1:37304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:01:13.874195Z","caller":"traceutil/trace.go:171","msg":"trace[1267979885] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:834; }","duration":"958.816817ms","start":"2026-06-15T19:01:12.915357Z","end":"2026-06-15T19:01:13.874174Z","steps":["trace[1267979885] 'read index received' (duration: 657.352521ms)","trace[1267979885] 'applied index is now lower than readState.Index' (duration: 301.463416ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:01:13.874306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.942229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:01:13.874295Z","caller":"traceutil/trace.go:171","msg":"trace[666955860] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"477.854819ms","start":"2026-06-15T19:01:13.39642Z","end":"2026-06-15T19:01:13.874275Z","steps":["trace[666955860] 'process raft request' (duration: 477.564614ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:13.87433Z","caller":"traceutil/trace.go:171","msg":"trace[1900421907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"958.98175ms","start":"2026-06-15T19:01:12.915342Z","end":"2026-06-15T19:01:13.874324Z","steps":["trace[1900421907] 'agreement among raft nodes before linearized reading' (duration: 958.926319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:13.874354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:12.915295Z","time spent":"959.052352ms","remote":"127.0.0.1:37030","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-06-15T19:01:13.874415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:13.396386Z","time spent":"477.963011ms","remote":"127.0.0.1:37240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T19:01:13.878701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.669475ms","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":"warn","ts":"2026-06-15T19:01:13.878762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.971645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-15T19:01:13.878753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.553404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T19:01:13.878776Z","caller":"traceutil/trace.go:171","msg":"trace[1121069635] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:807; }","duration":"361.811828ms","start":"2026-06-15T19:01:13.516948Z","end":"2026-06-15T19:01:13.878759Z","steps":["trace[1121069635] 'agreement among raft nodes before linearized reading' (duration: 361.661934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:13.878778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.936043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-15T19:01:13.878822Z","caller":"traceutil/trace.go:171","msg":"trace[408365717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"278.667745ms","start":"2026-06-15T19:01:13.600137Z","end":"2026-06-15T19:01:13.878805Z","steps":["trace[408365717] 'agreement among raft nodes before linearized reading' (duration: 278.492172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:13.878827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:01:13.516922Z","time spent":"361.894679ms","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-06-15T19:01:13.878842Z","caller":"traceutil/trace.go:171","msg":"trace[225156167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"196.136447ms","start":"2026-06-15T19:01:13.68269Z","end":"2026-06-15T19:01:13.878827Z","steps":["trace[225156167] 'agreement among raft nodes before linearized reading' (duration: 195.903712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:13.878912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.889976ms","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-06-15T19:01:13.878804Z","caller":"traceutil/trace.go:171","msg":"trace[1252660908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:807; }","duration":"150.037226ms","start":"2026-06-15T19:01:13.728755Z","end":"2026-06-15T19:01:13.878792Z","steps":["trace[1252660908] 'agreement among raft nodes before linearized reading' (duration: 149.900554ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:13.878938Z","caller":"traceutil/trace.go:171","msg":"trace[365761571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"155.945508ms","start":"2026-06-15T19:01:13.722985Z","end":"2026-06-15T19:01:13.878931Z","steps":["trace[365761571] 'agreement among raft nodes before linearized reading' (duration: 155.870426ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:01:13.878998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.359846ms","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-06-15T19:01:13.879027Z","caller":"traceutil/trace.go:171","msg":"trace[728635928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:807; }","duration":"192.432896ms","start":"2026-06-15T19:01:13.686585Z","end":"2026-06-15T19:01:13.879018Z","steps":["trace[728635928] 'agreement among raft nodes before linearized reading' (duration: 192.357965ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:40.240398Z","caller":"traceutil/trace.go:171","msg":"trace[531906599] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"152.914187ms","start":"2026-06-15T19:01:40.087458Z","end":"2026-06-15T19:01:40.240372Z","steps":["trace[531906599] 'process raft request' (duration: 152.867246ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:01:40.240501Z","caller":"traceutil/trace.go:171","msg":"trace[1056695055] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"154.630537ms","start":"2026-06-15T19:01:40.085843Z","end":"2026-06-15T19:01:40.240474Z","steps":["trace[1056695055] 'process raft request' (duration: 121.551407ms)","trace[1056695055] 'compare' (duration: 32.760954ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T19:01:40.2408Z","caller":"traceutil/trace.go:171","msg":"trace[1246209735] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"153.587868ms","start":"2026-06-15T19:01:40.087194Z","end":"2026-06-15T19:01:40.240782Z","steps":["trace[1246209735] 'process raft request' (duration: 153.08487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:21.76858Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197070100010234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T19:03:22.269502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197070100010234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T19:03:22.769779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592197070100010234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T19:03:22.902454Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.531053245s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T19:03:22.902832Z","caller":"traceutil/trace.go:171","msg":"trace[465062504] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"2.531515323s","start":"2026-06-15T19:03:20.371295Z","end":"2026-06-15T19:03:22.90281Z","steps":["trace[465062504] 'process raft request' (duration: 2.531369746s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.902981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:20.371274Z","time spent":"2.531629097s","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T19:03:22.903827Z","caller":"traceutil/trace.go:171","msg":"trace[796460208] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1452; }","duration":"2.531378297s","start":"2026-06-15T19:03:20.37243Z","end":"2026-06-15T19:03:22.903808Z","steps":["trace[796460208] 'read index received' (duration: 2.530211062s)","trace[796460208] 'applied index is now lower than readState.Index' (duration: 1.166345ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T19:03:22.904115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.531687458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T19:03:22.904156Z","caller":"traceutil/trace.go:171","msg":"trace[27391344] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:kubelet-bootstrap; range_end:; response_count:1; response_revision:1397; }","duration":"2.531757603s","start":"2026-06-15T19:03:20.372389Z","end":"2026-06-15T19:03:22.904146Z","steps":["trace[27391344] 'agreement among raft nodes before linearized reading' (duration: 2.531647658s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.904226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:20.372373Z","time spent":"2.531845316s","remote":"127.0.0.1:37464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":515,"request content":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" "} {"level":"warn","ts":"2026-06-15T19:03:22.904276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129453525s","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-06-15T19:03:22.904318Z","caller":"traceutil/trace.go:171","msg":"trace[1942784858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1397; }","duration":"1.129501758s","start":"2026-06-15T19:03:21.774804Z","end":"2026-06-15T19:03:22.904306Z","steps":["trace[1942784858] 'agreement among raft nodes before linearized reading' (duration: 1.129363993s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.904357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.774801Z","time spent":"1.129547209s","remote":"127.0.0.1:37344","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-06-15T19:03:22.904459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129685124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T19:03:22.904515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.763607ms","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":"warn","ts":"2026-06-15T19:03:22.904523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129718726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-15T19:03:22.90454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129733056s","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-06-15T19:03:22.904556Z","caller":"traceutil/trace.go:171","msg":"trace[862188543] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1397; }","duration":"197.83841ms","start":"2026-06-15T19:03:22.706708Z","end":"2026-06-15T19:03:22.904546Z","steps":["trace[862188543] 'agreement among raft nodes before linearized reading' (duration: 197.777177ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.904577Z","caller":"traceutil/trace.go:171","msg":"trace[373995929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1397; }","duration":"1.129814329s","start":"2026-06-15T19:03:21.774744Z","end":"2026-06-15T19:03:22.904559Z","steps":["trace[373995929] 'agreement among raft nodes before linearized reading' (duration: 1.129627302s)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.904581Z","caller":"traceutil/trace.go:171","msg":"trace[35784216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1397; }","duration":"1.129782988s","start":"2026-06-15T19:03:21.774786Z","end":"2026-06-15T19:03:22.904569Z","steps":["trace[35784216] 'agreement among raft nodes before linearized reading' (duration: 1.129672723s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.904612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.774783Z","time spent":"1.12982104s","remote":"127.0.0.1:37344","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-06-15T19:03:22.904612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.774736Z","time spent":"1.129867821s","remote":"127.0.0.1:37344","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":"info","ts":"2026-06-15T19:03:22.904661Z","caller":"traceutil/trace.go:171","msg":"trace[686384208] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1397; }","duration":"1.129807909s","start":"2026-06-15T19:03:21.774731Z","end":"2026-06-15T19:03:22.904539Z","steps":["trace[686384208] 'agreement among raft nodes before linearized reading' (duration: 1.129691295s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.904768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.774718Z","time spent":"1.130035107s","remote":"127.0.0.1:42946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:03:22.904844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129877271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:03:22.904881Z","caller":"traceutil/trace.go:171","msg":"trace[1967434958] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"1.130043119s","start":"2026-06-15T19:03:21.774826Z","end":"2026-06-15T19:03:22.904869Z","steps":["trace[1967434958] 'process raft request' (duration: 1.128858003s)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.904916Z","caller":"traceutil/trace.go:171","msg":"trace[2064097683] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1397; }","duration":"1.129957654s","start":"2026-06-15T19:03:21.774951Z","end":"2026-06-15T19:03:22.904909Z","steps":["trace[2064097683] 'agreement among raft nodes before linearized reading' (duration: 1.129871901s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.904949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.774289Z","time spent":"1.130652281s","remote":"127.0.0.1:37562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-06-15T19:03:22.904961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.774813Z","time spent":"1.13010711s","remote":"127.0.0.1:37344","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-06-15T19:03:22.904712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122862292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T19:03:22.905103Z","caller":"traceutil/trace.go:171","msg":"trace[368878493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1397; }","duration":"1.123260377s","start":"2026-06-15T19:03:21.781831Z","end":"2026-06-15T19:03:22.905091Z","steps":["trace[368878493] 'agreement among raft nodes before linearized reading' (duration: 1.122854751s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.905146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.781825Z","time spent":"1.123310659s","remote":"127.0.0.1:37030","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-06-15T19:03:22.904242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.508813875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-06-15T19:03:22.90527Z","caller":"traceutil/trace.go:171","msg":"trace[184757743] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1397; }","duration":"2.509899876s","start":"2026-06-15T19:03:20.395356Z","end":"2026-06-15T19:03:22.905256Z","steps":["trace[184757743] 'agreement among raft nodes before linearized reading' (duration: 2.508746401s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.905311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.276466267s","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-06-15T19:03:22.905312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:20.39534Z","time spent":"2.509960289s","remote":"127.0.0.1:37448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":748,"request content":"key:\"/registry/clusterroles/system:controller:namespace-controller\" "} {"level":"warn","ts":"2026-06-15T19:03:22.904786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12646583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-15T19:03:22.90534Z","caller":"traceutil/trace.go:171","msg":"trace[1232462199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1397; }","duration":"2.276522189s","start":"2026-06-15T19:03:20.628811Z","end":"2026-06-15T19:03:22.905333Z","steps":["trace[1232462199] 'agreement among raft nodes before linearized reading' (duration: 2.276448356s)"],"step_count":1} {"level":"info","ts":"2026-06-15T19:03:22.90538Z","caller":"traceutil/trace.go:171","msg":"trace[1905394976] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1397; }","duration":"1.127066153s","start":"2026-06-15T19:03:21.778303Z","end":"2026-06-15T19:03:22.905369Z","steps":["trace[1905394976] 'agreement among raft nodes before linearized reading' (duration: 1.126397837s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T19:03:22.905397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:20.628794Z","time spent":"2.276588762s","remote":"127.0.0.1:37344","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-06-15T19:03:22.905417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T19:03:21.778299Z","time spent":"1.127107094s","remote":"127.0.0.1:37344","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\" "}