{"level":"warn","ts":"2026-07-17T13:13:48.831758Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T13:13:48.83183Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-07-17T13:13:48.831887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T13:13:48.831895Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-17T13:13:48.831913Z","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-07-17T13:13:48.832499Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-07-17T13:13:48.832573Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-07-17T13:13:48.834739Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.997047ms"} {"level":"info","ts":"2026-07-17T13:13:48.83985Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-07-17T13:13:48.839914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T13:13:48.839934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-07-17T13:13:48.839951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T13:13:48.839963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-07-17T13:13:48.840006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-07-17T13:13:48.843889Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T13:13:48.845073Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T13:13:48.847178Z","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-07-17T13:13:48.850219Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T13:13:48.850867Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T13:13:48.851956Z","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-07-17T13:13:48.852061Z","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-07-17T13:13:48.852081Z","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-07-17T13:13:48.855491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-07-17T13:13:48.855685Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-17T13:13:48.857935Z","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-07-17T13:13:48.858074Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-17T13:13:48.858102Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-17T13:13:48.858146Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T13:13:48.85817Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T13:13:49.240381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T13:13:49.240438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T13:13:49.240455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-07-17T13:13:49.240468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-07-17T13:13:49.240476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-17T13:13:49.240487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-07-17T13:13:49.240496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-17T13:13:49.241339Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:13:49.24202Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T13:13:49.242138Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T13:13:49.24221Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T13:13:49.244502Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-07-17T13:13:49.244951Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T13:13:49.249479Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T13:13:49.249538Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:13:49.249633Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:13:49.249659Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T13:13:49.250009Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T13:14:32.170795Z","caller":"traceutil/trace.go:171","msg":"trace[1504351035] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"103.617739ms","start":"2026-07-17T13:14:32.06715Z","end":"2026-07-17T13:14:32.170767Z","steps":["trace[1504351035] 'process raft request' (duration: 103.433925ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:14:32.363813Z","caller":"traceutil/trace.go:171","msg":"trace[1073030046] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"135.105112ms","start":"2026-07-17T13:14:32.228681Z","end":"2026-07-17T13:14:32.363786Z","steps":["trace[1073030046] 'read index received' (duration: 134.383315ms)","trace[1073030046] 'applied index is now lower than readState.Index' (duration: 718.797µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:14:32.363894Z","caller":"traceutil/trace.go:171","msg":"trace[1247822995] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"295.404504ms","start":"2026-07-17T13:14:32.068452Z","end":"2026-07-17T13:14:32.363856Z","steps":["trace[1247822995] 'process raft request' (duration: 294.83315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:32.364445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.723987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hmjgg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-17T13:14:32.364956Z","caller":"traceutil/trace.go:171","msg":"trace[783437590] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hmjgg; range_end:; response_count:1; response_revision:596; }","duration":"136.261019ms","start":"2026-07-17T13:14:32.228646Z","end":"2026-07-17T13:14:32.364907Z","steps":["trace[783437590] 'agreement among raft nodes before linearized reading' (duration: 135.262355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:32.365089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.567499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-17T13:14:32.365207Z","caller":"traceutil/trace.go:171","msg":"trace[1274408347] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:596; }","duration":"108.777454ms","start":"2026-07-17T13:14:32.256415Z","end":"2026-07-17T13:14:32.365193Z","steps":["trace[1274408347] 'agreement among raft nodes before linearized reading' (duration: 108.426386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:32.932753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813334839110974,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T13:14:33.125661Z","caller":"traceutil/trace.go:171","msg":"trace[1037702269] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"739.989408ms","start":"2026-07-17T13:14:32.385641Z","end":"2026-07-17T13:14:33.12563Z","steps":["trace[1037702269] 'process raft request' (duration: 736.519894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:33.125919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.472142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-07-17T13:14:33.125961Z","caller":"traceutil/trace.go:171","msg":"trace[1348119656] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:599; }","duration":"693.566114ms","start":"2026-07-17T13:14:32.432383Z","end":"2026-07-17T13:14:33.12595Z","steps":["trace[1348119656] 'agreement among raft nodes before linearized reading' (duration: 693.447021ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:14:33.125957Z","caller":"traceutil/trace.go:171","msg":"trace[1317073466] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"707.551793ms","start":"2026-07-17T13:14:32.418378Z","end":"2026-07-17T13:14:33.12593Z","steps":["trace[1317073466] 'process raft request' (duration: 707.185974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:33.125999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:32.432358Z","time spent":"693.633836ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-17T13:14:33.125787Z","caller":"traceutil/trace.go:171","msg":"trace[1743382603] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"693.321329ms","start":"2026-07-17T13:14:32.432442Z","end":"2026-07-17T13:14:33.125763Z","steps":["trace[1743382603] 'read index received' (duration: 689.752712ms)","trace[1743382603] 'applied index is now lower than readState.Index' (duration: 3.567647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:14:33.12644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:32.41836Z","time spent":"707.685615ms","remote":"127.0.0.1:35854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T13:14:33.126511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:32.385622Z","time spent":"740.243794ms","remote":"127.0.0.1:35512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:14:33.12652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.253431ms","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-07-17T13:14:33.126696Z","caller":"traceutil/trace.go:171","msg":"trace[1848885236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"490.452875ms","start":"2026-07-17T13:14:32.636199Z","end":"2026-07-17T13:14:33.126652Z","steps":["trace[1848885236] 'agreement among raft nodes before linearized reading' (duration: 490.181999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:33.126749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:32.636182Z","time spent":"490.552848ms","remote":"127.0.0.1:35584","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-07-17T13:14:45.263166Z","caller":"traceutil/trace.go:171","msg":"trace[1687361891] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"230.625145ms","start":"2026-07-17T13:14:45.032518Z","end":"2026-07-17T13:14:45.263143Z","steps":["trace[1687361891] 'process raft request' (duration: 230.163114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:48.567853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.634726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:14:48.567981Z","caller":"traceutil/trace.go:171","msg":"trace[1219623269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"273.79757ms","start":"2026-07-17T13:14:48.294155Z","end":"2026-07-17T13:14:48.567953Z","steps":["trace[1219623269] 'range keys from in-memory index tree' (duration: 273.474342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:48.568456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.461726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T13:14:48.56856Z","caller":"traceutil/trace.go:171","msg":"trace[1612902490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"326.628879ms","start":"2026-07-17T13:14:48.241908Z","end":"2026-07-17T13:14:48.568537Z","steps":["trace[1612902490] 'range keys from in-memory index tree' (duration: 326.195869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:48.568609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:48.241872Z","time spent":"326.728532ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T13:14:48.811299Z","caller":"traceutil/trace.go:171","msg":"trace[1758677940] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"436.531151ms","start":"2026-07-17T13:14:48.374746Z","end":"2026-07-17T13:14:48.811277Z","steps":["trace[1758677940] 'read index received' (duration: 436.394168ms)","trace[1758677940] 'applied index is now lower than readState.Index' (duration: 136.063µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:14:48.811401Z","caller":"traceutil/trace.go:171","msg":"trace[169655486] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"469.044798ms","start":"2026-07-17T13:14:48.342348Z","end":"2026-07-17T13:14:48.811393Z","steps":["trace[169655486] 'process raft request' (duration: 468.805722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:48.811494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:48.342322Z","time spent":"469.10387ms","remote":"127.0.0.1:35562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:14:48.811835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.074914ms","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-07-17T13:14:48.81193Z","caller":"traceutil/trace.go:171","msg":"trace[73509549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"437.205497ms","start":"2026-07-17T13:14:48.374695Z","end":"2026-07-17T13:14:48.811901Z","steps":["trace[73509549] 'agreement among raft nodes before linearized reading' (duration: 436.868699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:48.811972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:48.374676Z","time spent":"437.28767ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T13:14:48.874115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.602816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:14:48.874304Z","caller":"traceutil/trace.go:171","msg":"trace[89948106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"304.840222ms","start":"2026-07-17T13:14:48.56944Z","end":"2026-07-17T13:14:48.87428Z","steps":["trace[89948106] 'agreement among raft nodes before linearized reading' (duration: 304.563215ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:14:48.874346Z","caller":"traceutil/trace.go:171","msg":"trace[417733063] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"302.387172ms","start":"2026-07-17T13:14:48.571753Z","end":"2026-07-17T13:14:48.87414Z","steps":["trace[417733063] 'process raft request' (duration: 300.405955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:14:48.874632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:48.569422Z","time spent":"305.023137ms","remote":"127.0.0.1:35350","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-07-17T13:14:48.874747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:14:48.571739Z","time spent":"302.916515ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:15:23.605351Z","caller":"traceutil/trace.go:171","msg":"trace[1807415531] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"125.68845ms","start":"2026-07-17T13:15:23.47964Z","end":"2026-07-17T13:15:23.605328Z","steps":["trace[1807415531] 'read index received' (duration: 125.469215ms)","trace[1807415531] 'applied index is now lower than readState.Index' (duration: 218.075µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:15:23.605575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.914806ms","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-07-17T13:15:23.605611Z","caller":"traceutil/trace.go:171","msg":"trace[1225209439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"126.020968ms","start":"2026-07-17T13:15:23.479579Z","end":"2026-07-17T13:15:23.6056Z","steps":["trace[1225209439] 'agreement among raft nodes before linearized reading' (duration: 125.871364ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:15:23.605571Z","caller":"traceutil/trace.go:171","msg":"trace[1797037218] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"133.793488ms","start":"2026-07-17T13:15:23.471697Z","end":"2026-07-17T13:15:23.60549Z","steps":["trace[1797037218] 'process raft request' (duration: 133.471141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:23.605719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.076345ms","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-07-17T13:15:23.605805Z","caller":"traceutil/trace.go:171","msg":"trace[965680122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"116.218159ms","start":"2026-07-17T13:15:23.489566Z","end":"2026-07-17T13:15:23.605784Z","steps":["trace[965680122] 'agreement among raft nodes before linearized reading' (duration: 115.976303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:25.79458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813334839111769,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T13:15:25.805746Z","caller":"traceutil/trace.go:171","msg":"trace[1766969457] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"765.548139ms","start":"2026-07-17T13:15:25.040176Z","end":"2026-07-17T13:15:25.805724Z","steps":["trace[1766969457] 'process raft request' (duration: 765.411206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:25.805882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.040161Z","time spent":"765.649322ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:15:26.381237Z","caller":"traceutil/trace.go:171","msg":"trace[1985518340] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1020; }","duration":"1.087410691s","start":"2026-07-17T13:15:25.293801Z","end":"2026-07-17T13:15:26.381212Z","steps":["trace[1985518340] 'read index received' (duration: 511.75673ms)","trace[1985518340] 'applied index is now lower than readState.Index' (duration: 575.652951ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:15:26.381285Z","caller":"traceutil/trace.go:171","msg":"trace[1833357839] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.339034237s","start":"2026-07-17T13:15:25.042225Z","end":"2026-07-17T13:15:26.38126Z","steps":["trace[1833357839] 'process raft request' (duration: 1.338798191s)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:15:26.381341Z","caller":"traceutil/trace.go:171","msg":"trace[1534396755] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"770.135491ms","start":"2026-07-17T13:15:25.611171Z","end":"2026-07-17T13:15:26.381307Z","steps":["trace[1534396755] 'process raft request' (duration: 769.986298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:26.381436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087625167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:15:26.381475Z","caller":"traceutil/trace.go:171","msg":"trace[1306749828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"1.087709009s","start":"2026-07-17T13:15:25.293753Z","end":"2026-07-17T13:15:26.381462Z","steps":["trace[1306749828] 'agreement among raft nodes before linearized reading' (duration: 1.087580996s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:26.381494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.611151Z","time spent":"770.257125ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:15:26.381509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.293734Z","time spent":"1.0877674s","remote":"127.0.0.1:35348","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-07-17T13:15:26.381526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.042213Z","time spent":"1.339139771s","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:15:26.382737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.551668ms","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-07-17T13:15:26.382848Z","caller":"traceutil/trace.go:171","msg":"trace[875320838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"758.702752ms","start":"2026-07-17T13:15:25.624116Z","end":"2026-07-17T13:15:26.382819Z","steps":["trace[875320838] 'agreement among raft nodes before linearized reading' (duration: 758.404215ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:26.382905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.624103Z","time spent":"758.791804ms","remote":"127.0.0.1:35584","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-07-17T13:15:26.383209Z","caller":"traceutil/trace.go:171","msg":"trace[395426704] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"416.475403ms","start":"2026-07-17T13:15:25.966719Z","end":"2026-07-17T13:15:26.383195Z","steps":["trace[395426704] 'process raft request' (duration: 415.649642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:26.38332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.966702Z","time spent":"416.543865ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:15:26.383531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.122798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T13:15:26.383564Z","caller":"traceutil/trace.go:171","msg":"trace[2082842040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"724.188909ms","start":"2026-07-17T13:15:25.659365Z","end":"2026-07-17T13:15:26.383554Z","steps":["trace[2082842040] 'agreement among raft nodes before linearized reading' (duration: 724.095877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:26.383592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.659331Z","time spent":"724.255361ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T13:15:26.383897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.76001ms","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-07-17T13:15:26.383938Z","caller":"traceutil/trace.go:171","msg":"trace[1338998400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"737.830242ms","start":"2026-07-17T13:15:25.646098Z","end":"2026-07-17T13:15:26.383928Z","steps":["trace[1338998400] 'agreement among raft nodes before linearized reading' (duration: 737.703969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:26.383969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:25.646083Z","time spent":"737.878333ms","remote":"127.0.0.1:35584","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-07-17T13:15:49.127314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813334839111971,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T13:15:49.550474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.08969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:15:49.550594Z","caller":"traceutil/trace.go:171","msg":"trace[32035005] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.050130751s","start":"2026-07-17T13:15:48.500443Z","end":"2026-07-17T13:15:49.550574Z","steps":["trace[32035005] 'process raft request' (duration: 941.645901ms)","trace[32035005] 'compare' (duration: 107.828834ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:15:49.550657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:48.500434Z","time spent":"1.050194442s","remote":"127.0.0.1:35584","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-07-17T13:15:49.551901Z","caller":"traceutil/trace.go:171","msg":"trace[1360077560] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"1.051270699s","start":"2026-07-17T13:15:48.500604Z","end":"2026-07-17T13:15:49.551874Z","steps":["trace[1360077560] 'process raft request' (duration: 1.050842258s)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:15:49.55212Z","caller":"traceutil/trace.go:171","msg":"trace[1864501339] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1100; }","duration":"925.24183ms","start":"2026-07-17T13:15:48.626784Z","end":"2026-07-17T13:15:49.552026Z","steps":["trace[1864501339] 'read index received' (duration: 815.320615ms)","trace[1864501339] 'applied index is now lower than readState.Index' (duration: 109.920275ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:15:49.552239Z","caller":"traceutil/trace.go:171","msg":"trace[1910496595] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"1.050380947s","start":"2026-07-17T13:15:48.501666Z","end":"2026-07-17T13:15:49.552047Z","steps":["trace[1910496595] 'process raft request' (duration: 1.05008632s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:49.552233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.429495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:15:49.552393Z","caller":"traceutil/trace.go:171","msg":"trace[1360321616] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1061; }","duration":"925.64728ms","start":"2026-07-17T13:15:48.626729Z","end":"2026-07-17T13:15:49.552377Z","steps":["trace[1360321616] 'agreement among raft nodes before linearized reading' (duration: 925.422204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:49.552396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:48.501644Z","time spent":"1.050676585s","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:15:49.552432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:48.626707Z","time spent":"925.716302ms","remote":"127.0.0.1:56720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-17T13:15:49.552395Z","caller":"traceutil/trace.go:171","msg":"trace[1493421646] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.048794379s","start":"2026-07-17T13:15:48.503281Z","end":"2026-07-17T13:15:49.552075Z","steps":["trace[1493421646] 'process raft request' (duration: 1.048706386s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:49.553075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:48.503271Z","time spent":"1.049770942s","remote":"127.0.0.1:35584","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-07-17T13:15:49.553177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.015188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:15:49.553368Z","caller":"traceutil/trace.go:171","msg":"trace[2023673495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"258.056343ms","start":"2026-07-17T13:15:49.295271Z","end":"2026-07-17T13:15:49.553327Z","steps":["trace[2023673495] 'agreement among raft nodes before linearized reading' (duration: 257.038628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:15:49.553414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:15:48.500599Z","time spent":"1.052698593s","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:15:50.608182Z","caller":"traceutil/trace.go:171","msg":"trace[785526785] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"102.518534ms","start":"2026-07-17T13:15:50.505634Z","end":"2026-07-17T13:15:50.608152Z","steps":["trace[785526785] 'process raft request' (duration: 102.374641ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:15:52.533503Z","caller":"traceutil/trace.go:171","msg":"trace[1311431914] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"239.253744ms","start":"2026-07-17T13:15:52.294218Z","end":"2026-07-17T13:15:52.533472Z","steps":["trace[1311431914] 'read index received' (duration: 238.993748ms)","trace[1311431914] 'applied index is now lower than readState.Index' (duration: 258.436µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:15:52.533926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.679145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:15:52.53411Z","caller":"traceutil/trace.go:171","msg":"trace[1165243620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1068; }","duration":"239.875799ms","start":"2026-07-17T13:15:52.294139Z","end":"2026-07-17T13:15:52.534015Z","steps":["trace[1165243620] 'agreement among raft nodes before linearized reading' (duration: 239.474209ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:15:53.482142Z","caller":"traceutil/trace.go:171","msg":"trace[1317385919] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"104.052992ms","start":"2026-07-17T13:15:53.378056Z","end":"2026-07-17T13:15:53.482109Z","steps":["trace[1317385919] 'process raft request' (duration: 103.812196ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:09.345106Z","caller":"traceutil/trace.go:171","msg":"trace[993405459] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"618.232403ms","start":"2026-07-17T13:16:08.72681Z","end":"2026-07-17T13:16:09.345043Z","steps":["trace[993405459] 'process raft request' (duration: 617.69942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:09.345339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:08.726775Z","time spent":"618.451708ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:16:26.978915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.961746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:16:26.978986Z","caller":"traceutil/trace.go:171","msg":"trace[1567010256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"686.064609ms","start":"2026-07-17T13:16:26.292904Z","end":"2026-07-17T13:16:26.978969Z","steps":["trace[1567010256] 'range keys from in-memory index tree' (duration: 685.906905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:26.979016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:26.29289Z","time spent":"686.12019ms","remote":"127.0.0.1:35348","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-07-17T13:16:28.152425Z","caller":"traceutil/trace.go:171","msg":"trace[142961448] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"361.779462ms","start":"2026-07-17T13:16:27.790617Z","end":"2026-07-17T13:16:28.152397Z","steps":["trace[142961448] 'read index received' (duration: 342.490532ms)","trace[142961448] 'applied index is now lower than readState.Index' (duration: 19.28778ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:16:28.15247Z","caller":"traceutil/trace.go:171","msg":"trace[1493182784] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"362.287784ms","start":"2026-07-17T13:16:27.790145Z","end":"2026-07-17T13:16:28.152432Z","steps":["trace[1493182784] 'process raft request' (duration: 342.986943ms)","trace[1493182784] 'compare' (duration: 19.067296ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:16:28.152611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:27.790131Z","time spent":"362.405778ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:16:28.152785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.147831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T13:16:28.152907Z","caller":"traceutil/trace.go:171","msg":"trace[1016900713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"362.284885ms","start":"2026-07-17T13:16:27.790574Z","end":"2026-07-17T13:16:28.152859Z","steps":["trace[1016900713] 'agreement among raft nodes before linearized reading' (duration: 361.916356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:28.152959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:27.790555Z","time spent":"362.394187ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T13:16:28.653527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813334839112414,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T13:16:28.917382Z","caller":"traceutil/trace.go:171","msg":"trace[208453236] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"920.85411ms","start":"2026-07-17T13:16:27.996422Z","end":"2026-07-17T13:16:28.917276Z","steps":["trace[208453236] 'process raft request' (duration: 920.655675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:28.91757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:27.996404Z","time spent":"921.067146ms","remote":"127.0.0.1:35500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:16:28.952953Z","caller":"traceutil/trace.go:171","msg":"trace[542868123] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"795.500271ms","start":"2026-07-17T13:16:28.157429Z","end":"2026-07-17T13:16:28.952929Z","steps":["trace[542868123] 'process raft request' (duration: 795.285806ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:28.95294Z","caller":"traceutil/trace.go:171","msg":"trace[1299681106] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1309; }","duration":"800.40949ms","start":"2026-07-17T13:16:28.152499Z","end":"2026-07-17T13:16:28.952909Z","steps":["trace[1299681106] 'read index received' (duration: 764.618756ms)","trace[1299681106] 'applied index is now lower than readState.Index' (duration: 35.789384ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:16:28.953068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:28.157408Z","time spent":"795.603333ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:16:28.953216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158209745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T13:16:28.953243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.244263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:16:28.953264Z","caller":"traceutil/trace.go:171","msg":"trace[1162153778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"1.158287578s","start":"2026-07-17T13:16:27.794962Z","end":"2026-07-17T13:16:28.95325Z","steps":["trace[1162153778] 'agreement among raft nodes before linearized reading' (duration: 1.158111803s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:28.953218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152606169s","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-07-17T13:16:28.953317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:27.794955Z","time spent":"1.158341429s","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T13:16:28.953325Z","caller":"traceutil/trace.go:171","msg":"trace[975163831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"1.152751342s","start":"2026-07-17T13:16:27.800553Z","end":"2026-07-17T13:16:28.953304Z","steps":["trace[975163831] 'agreement among raft nodes before linearized reading' (duration: 1.152429764s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:28.953343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.492398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T13:16:28.953429Z","caller":"traceutil/trace.go:171","msg":"trace[1805475796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"756.638602ms","start":"2026-07-17T13:16:28.196772Z","end":"2026-07-17T13:16:28.953411Z","steps":["trace[1805475796] 'agreement among raft nodes before linearized reading' (duration: 756.404256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:28.953356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.815652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T13:16:28.953478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:28.196749Z","time spent":"756.720294ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T13:16:28.953512Z","caller":"traceutil/trace.go:171","msg":"trace[1313309956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"660.996527ms","start":"2026-07-17T13:16:28.292502Z","end":"2026-07-17T13:16:28.953499Z","steps":["trace[1313309956] 'agreement among raft nodes before linearized reading' (duration: 660.812602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:28.953559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:28.292485Z","time spent":"661.066428ms","remote":"127.0.0.1:35348","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-07-17T13:16:28.953373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:27.800544Z","time spent":"1.152820243s","remote":"127.0.0.1:35584","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-07-17T13:16:28.953277Z","caller":"traceutil/trace.go:171","msg":"trace[1470095793] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1258; }","duration":"158.344576ms","start":"2026-07-17T13:16:28.794925Z","end":"2026-07-17T13:16:28.95327Z","steps":["trace[1470095793] 'agreement among raft nodes before linearized reading' (duration: 158.263714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:29.071806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.829552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:16:29.071875Z","caller":"traceutil/trace.go:171","msg":"trace[167591828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"116.937915ms","start":"2026-07-17T13:16:28.954919Z","end":"2026-07-17T13:16:29.071857Z","steps":["trace[167591828] 'range keys from in-memory index tree' (duration: 116.776521ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:29.072066Z","caller":"traceutil/trace.go:171","msg":"trace[536148193] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"114.167308ms","start":"2026-07-17T13:16:28.957881Z","end":"2026-07-17T13:16:29.072049Z","steps":["trace[536148193] 'process raft request' (duration: 114.143157ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:29.072222Z","caller":"traceutil/trace.go:171","msg":"trace[1076064864] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"114.408233ms","start":"2026-07-17T13:16:28.957807Z","end":"2026-07-17T13:16:29.072215Z","steps":["trace[1076064864] 'process raft request' (duration: 17.787954ms)","trace[1076064864] 'compare' (duration: 96.322122ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:16:29.072268Z","caller":"traceutil/trace.go:171","msg":"trace[1739839298] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"114.450004ms","start":"2026-07-17T13:16:28.957814Z","end":"2026-07-17T13:16:29.072264Z","steps":["trace[1739839298] 'process raft request' (duration: 114.184628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:29.500008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.952351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:16:29.50019Z","caller":"traceutil/trace.go:171","msg":"trace[1413749729] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1318; }","duration":"206.43396ms","start":"2026-07-17T13:16:29.293739Z","end":"2026-07-17T13:16:29.500173Z","steps":["trace[1413749729] 'read index received' (duration: 81.277474ms)","trace[1413749729] 'applied index is now lower than readState.Index' (duration: 125.155626ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:16:29.500264Z","caller":"traceutil/trace.go:171","msg":"trace[1255959869] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"230.894947ms","start":"2026-07-17T13:16:29.269329Z","end":"2026-07-17T13:16:29.500224Z","steps":["trace[1255959869] 'process raft request' (duration: 105.67175ms)","trace[1255959869] 'compare' (duration: 124.841178ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:16:29.500276Z","caller":"traceutil/trace.go:171","msg":"trace[702413645] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"230.530298ms","start":"2026-07-17T13:16:29.26971Z","end":"2026-07-17T13:16:29.50024Z","steps":["trace[702413645] 'process raft request' (duration: 230.402885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:29.500326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.551522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:16:29.500353Z","caller":"traceutil/trace.go:171","msg":"trace[1327398897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"206.630595ms","start":"2026-07-17T13:16:29.293713Z","end":"2026-07-17T13:16:29.500343Z","steps":["trace[1327398897] 'agreement among raft nodes before linearized reading' (duration: 206.551113ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:30.320971Z","caller":"traceutil/trace.go:171","msg":"trace[866185999] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"160.844666ms","start":"2026-07-17T13:16:30.160096Z","end":"2026-07-17T13:16:30.32094Z","steps":["trace[866185999] 'process raft request' (duration: 160.688313ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:40.62221Z","caller":"traceutil/trace.go:171","msg":"trace[1569296432] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"259.171838ms","start":"2026-07-17T13:16:40.363021Z","end":"2026-07-17T13:16:40.622193Z","steps":["trace[1569296432] 'read index received' (duration: 259.058285ms)","trace[1569296432] 'applied index is now lower than readState.Index' (duration: 112.663µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:16:40.622281Z","caller":"traceutil/trace.go:171","msg":"trace[1166664528] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"272.729488ms","start":"2026-07-17T13:16:40.349516Z","end":"2026-07-17T13:16:40.622246Z","steps":["trace[1166664528] 'process raft request' (duration: 272.556474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:40.622381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.343132ms","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-07-17T13:16:40.622401Z","caller":"traceutil/trace.go:171","msg":"trace[358550005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1341; }","duration":"259.417714ms","start":"2026-07-17T13:16:40.362977Z","end":"2026-07-17T13:16:40.622395Z","steps":["trace[358550005] 'agreement among raft nodes before linearized reading' (duration: 259.314512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:40.622473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.639289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:16:40.622562Z","caller":"traceutil/trace.go:171","msg":"trace[1598236128] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1341; }","duration":"140.700776ms","start":"2026-07-17T13:16:40.48184Z","end":"2026-07-17T13:16:40.622541Z","steps":["trace[1598236128] 'agreement among raft nodes before linearized reading' (duration: 140.545382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:41.695434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.431357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T13:16:41.695528Z","caller":"traceutil/trace.go:171","msg":"trace[208645480] linearizableReadLoop","detail":"{readStateIndex:1397; appliedIndex:1396; }","duration":"623.813708ms","start":"2026-07-17T13:16:41.071699Z","end":"2026-07-17T13:16:41.695513Z","steps":["trace[208645480] 'read index received' (duration: 32.221µs)","trace[208645480] 'applied index is now lower than readState.Index' (duration: 623.780157ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T13:16:41.695612Z","caller":"traceutil/trace.go:171","msg":"trace[1545129478] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.069920038s","start":"2026-07-17T13:16:40.625653Z","end":"2026-07-17T13:16:41.695573Z","steps":["trace[1545129478] 'process raft request' (duration: 197.259545ms)","trace[1545129478] 'compare' (duration: 872.237852ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:16:41.695675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.984512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T13:16:41.695703Z","caller":"traceutil/trace.go:171","msg":"trace[1145614680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1342; }","duration":"624.047134ms","start":"2026-07-17T13:16:41.071647Z","end":"2026-07-17T13:16:41.695694Z","steps":["trace[1145614680] 'agreement among raft nodes before linearized reading' (duration: 623.928531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:41.695734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.071628Z","time spent":"624.099005ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T13:16:41.695815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:40.625632Z","time spent":"1.070072642s","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T13:16:42.014621Z","caller":"traceutil/trace.go:171","msg":"trace[819206561] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"383.199395ms","start":"2026-07-17T13:16:41.631401Z","end":"2026-07-17T13:16:42.014601Z","steps":["trace[819206561] 'process raft request' (duration: 382.882327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.014736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.63137Z","time spent":"383.307438ms","remote":"127.0.0.1:35584","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-07-17T13:16:42.014854Z","caller":"traceutil/trace.go:171","msg":"trace[1637392438] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1398; }","duration":"319.275594ms","start":"2026-07-17T13:16:41.695553Z","end":"2026-07-17T13:16:42.014829Z","steps":["trace[1637392438] 'read index received' (duration: 319.265274ms)","trace[1637392438] 'applied index is now lower than readState.Index' (duration: 8.29µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T13:16:42.015116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.265406ms","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-07-17T13:16:42.015054Z","caller":"traceutil/trace.go:171","msg":"trace[160731461] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"315.117003ms","start":"2026-07-17T13:16:41.699921Z","end":"2026-07-17T13:16:42.015038Z","steps":["trace[160731461] 'process raft request' (duration: 315.03017ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:42.015148Z","caller":"traceutil/trace.go:171","msg":"trace[109465277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1344; }","duration":"791.319037ms","start":"2026-07-17T13:16:41.22382Z","end":"2026-07-17T13:16:42.015139Z","steps":["trace[109465277] 'agreement among raft nodes before linearized reading' (duration: 791.207674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.01514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.163765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T13:16:42.015166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.22381Z","time spent":"791.351498ms","remote":"127.0.0.1:35584","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":"info","ts":"2026-07-17T13:16:42.015177Z","caller":"traceutil/trace.go:171","msg":"trace[951700394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"721.238307ms","start":"2026-07-17T13:16:41.293928Z","end":"2026-07-17T13:16:42.015167Z","steps":["trace[951700394] 'agreement among raft nodes before linearized reading' (duration: 721.115654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.015193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.699902Z","time spent":"315.251615ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T13:16:42.015209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.293918Z","time spent":"721.283828ms","remote":"127.0.0.1:35350","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-07-17T13:16:42.015258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.592699ms","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-07-17T13:16:42.015279Z","caller":"traceutil/trace.go:171","msg":"trace[225068349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1344; }","duration":"783.64768ms","start":"2026-07-17T13:16:41.231627Z","end":"2026-07-17T13:16:42.015275Z","steps":["trace[225068349] 'agreement among raft nodes before linearized reading' (duration: 783.601129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.015294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.231606Z","time spent":"783.684511ms","remote":"127.0.0.1:35584","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-07-17T13:16:42.01522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.568047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:16:42.015362Z","caller":"traceutil/trace.go:171","msg":"trace[1146952815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"726.73184ms","start":"2026-07-17T13:16:41.288625Z","end":"2026-07-17T13:16:42.015357Z","steps":["trace[1146952815] 'agreement among raft nodes before linearized reading' (duration: 726.580067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.015377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.288613Z","time spent":"726.760672ms","remote":"127.0.0.1:35348","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-07-17T13:16:42.015429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.691327ms","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-07-17T13:16:42.015466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.527414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T13:16:42.015465Z","caller":"traceutil/trace.go:171","msg":"trace[639636072] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1344; }","duration":"832.731319ms","start":"2026-07-17T13:16:41.182726Z","end":"2026-07-17T13:16:42.015457Z","steps":["trace[639636072] 'agreement among raft nodes before linearized reading' (duration: 832.666537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.01547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.298075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-17T13:16:42.015496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.182647Z","time spent":"832.839991ms","remote":"127.0.0.1:35526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-07-17T13:16:42.015504Z","caller":"traceutil/trace.go:171","msg":"trace[1345680058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"795.354776ms","start":"2026-07-17T13:16:41.220139Z","end":"2026-07-17T13:16:42.015494Z","steps":["trace[1345680058] 'agreement among raft nodes before linearized reading' (duration: 795.264414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.015532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.220128Z","time spent":"795.397937ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T13:16:42.015566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.209907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T13:16:42.015483Z","caller":"traceutil/trace.go:171","msg":"trace[2063503185] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1344; }","duration":"576.572804ms","start":"2026-07-17T13:16:41.438905Z","end":"2026-07-17T13:16:42.015478Z","steps":["trace[2063503185] 'agreement among raft nodes before linearized reading' (duration: 576.545704ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T13:16:42.015601Z","caller":"traceutil/trace.go:171","msg":"trace[1025417690] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1344; }","duration":"234.266829ms","start":"2026-07-17T13:16:41.781315Z","end":"2026-07-17T13:16:42.015582Z","steps":["trace[1025417690] 'agreement among raft nodes before linearized reading' (duration: 234.233798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.015611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.438891Z","time spent":"576.711438ms","remote":"127.0.0.1:35542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-17T13:16:42.016241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.575243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-17T13:16:42.016285Z","caller":"traceutil/trace.go:171","msg":"trace[1805596332] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1343; }","duration":"537.659525ms","start":"2026-07-17T13:16:41.478614Z","end":"2026-07-17T13:16:42.016274Z","steps":["trace[1805596332] 'agreement among raft nodes before linearized reading' (duration: 536.344613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T13:16:42.016318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T13:16:41.478593Z","time spent":"537.714306ms","remote":"127.0.0.1:35584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}