{"level":"warn","ts":"2026-04-24T20:54:24.094881Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T20:54:24.094966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-04-24T20:54:24.095022Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T20:54:24.09503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-24T20:54:24.095047Z","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-04-24T20:54:24.095693Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-24T20:54:24.09577Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-04-24T20:54:24.099088Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.141883ms"} {"level":"info","ts":"2026-04-24T20:54:24.103853Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-24T20:54:24.103932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T20:54:24.103958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-24T20:54:24.103977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T20:54:24.103998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-24T20:54:24.104038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-24T20:54:24.109411Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T20:54:24.110671Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T20:54:24.111513Z","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-04-24T20:54:24.113233Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T20:54:24.113427Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T20:54:24.113557Z","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-04-24T20:54:24.113643Z","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-04-24T20:54:24.113651Z","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-04-24T20:54:24.11616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-24T20:54:24.116361Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-24T20:54:24.118224Z","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-04-24T20:54:24.118377Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-24T20:54:24.118424Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-24T20:54:24.118501Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T20:54:24.118578Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T20:54:24.805152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T20:54:24.805241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T20:54:24.805274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-24T20:54:24.805306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-24T20:54:24.805325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-24T20:54:24.805341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-24T20:54:24.80536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-24T20:54:24.807178Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T20:54:24.808994Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T20:54:24.80916Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T20:54:24.809417Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T20:54:24.810372Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T20:54:24.810417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-24T20:54:24.810822Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T20:54:24.81096Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T20:54:24.812836Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T20:54:24.812037Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-24T20:54:24.812627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-24T20:54:53.915048Z","caller":"traceutil/trace.go:171","msg":"trace[1945993900] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"150.136636ms","start":"2026-04-24T20:54:53.764881Z","end":"2026-04-24T20:54:53.915018Z","steps":["trace[1945993900] 'process raft request' (duration: 95.106417ms)","trace[1945993900] 'compare' (duration: 54.902076ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T20:54:53.959171Z","caller":"traceutil/trace.go:171","msg":"trace[829582106] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"150.531868ms","start":"2026-04-24T20:54:53.808598Z","end":"2026-04-24T20:54:53.95913Z","steps":["trace[829582106] 'process raft request' (duration: 150.306162ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:54:53.959219Z","caller":"traceutil/trace.go:171","msg":"trace[694121210] transaction","detail":"{read_only:false; number_of_response:1; response_revision:553; }","duration":"149.360667ms","start":"2026-04-24T20:54:53.809837Z","end":"2026-04-24T20:54:53.959198Z","steps":["trace[694121210] 'process raft request' (duration: 149.179732ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:55:00.045378Z","caller":"traceutil/trace.go:171","msg":"trace[95229946] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"140.450457ms","start":"2026-04-24T20:54:59.904905Z","end":"2026-04-24T20:55:00.045355Z","steps":["trace[95229946] 'process raft request' (duration: 139.903402ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:55:00.201575Z","caller":"traceutil/trace.go:171","msg":"trace[2062008106] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"287.307975ms","start":"2026-04-24T20:54:59.91424Z","end":"2026-04-24T20:55:00.201548Z","steps":["trace[2062008106] 'process raft request' (duration: 287.15067ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:55:00.209162Z","caller":"traceutil/trace.go:171","msg":"trace[1502435837] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"105.348523ms","start":"2026-04-24T20:55:00.103785Z","end":"2026-04-24T20:55:00.209134Z","steps":["trace[1502435837] 'process raft request' (duration: 105.183508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:55:00.797311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.997982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-24T20:55:00.797379Z","caller":"traceutil/trace.go:171","msg":"trace[1155366287] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"467.873451ms","start":"2026-04-24T20:55:00.329496Z","end":"2026-04-24T20:55:00.79737Z","steps":["trace[1155366287] 'read index received' (duration: 233.56267ms)","trace[1155366287] 'applied index is now lower than readState.Index' (duration: 234.310151ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T20:55:00.797425Z","caller":"traceutil/trace.go:171","msg":"trace[304958358] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"558.550729ms","start":"2026-04-24T20:55:00.23887Z","end":"2026-04-24T20:55:00.79742Z","steps":["trace[304958358] 'process raft request' (duration: 324.185197ms)","trace[304958358] 'compare' (duration: 233.861169ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T20:55:00.797449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:55:00.238858Z","time spent":"558.58052ms","remote":"127.0.0.1:36854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T20:55:00.79767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.171498ms","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-04-24T20:55:00.797714Z","caller":"traceutil/trace.go:171","msg":"trace[618183208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"468.248251ms","start":"2026-04-24T20:55:00.329461Z","end":"2026-04-24T20:55:00.797709Z","steps":["trace[618183208] 'agreement among raft nodes before linearized reading' (duration: 468.136348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:55:00.797729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:55:00.329442Z","time spent":"468.283482ms","remote":"127.0.0.1:36936","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-04-24T20:55:00.797758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.252184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-24T20:55:00.797759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.084309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T20:55:00.797803Z","caller":"traceutil/trace.go:171","msg":"trace[1207964245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"171.335417ms","start":"2026-04-24T20:55:00.626452Z","end":"2026-04-24T20:55:00.797788Z","steps":["trace[1207964245] 'agreement among raft nodes before linearized reading' (duration: 171.181953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:55:00.797872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.868006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dv6wv\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-24T20:55:00.797862Z","caller":"traceutil/trace.go:171","msg":"trace[1935613956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"329.241763ms","start":"2026-04-24T20:55:00.468586Z","end":"2026-04-24T20:55:00.797827Z","steps":["trace[1935613956] 'agreement among raft nodes before linearized reading' (duration: 329.043007ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:55:00.797887Z","caller":"traceutil/trace.go:171","msg":"trace[772135363] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dv6wv; range_end:; response_count:1; response_revision:616; }","duration":"314.916147ms","start":"2026-04-24T20:55:00.482966Z","end":"2026-04-24T20:55:00.797883Z","steps":["trace[772135363] 'agreement among raft nodes before linearized reading' (duration: 314.872526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:55:00.797902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:55:00.48295Z","time spent":"314.949349ms","remote":"127.0.0.1:36854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-dv6wv\" "} {"level":"warn","ts":"2026-04-24T20:55:00.797921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:55:00.468524Z","time spent":"329.382346ms","remote":"127.0.0.1:36634","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-04-24T20:55:54.892219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.64136ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T20:55:54.892326Z","caller":"traceutil/trace.go:171","msg":"trace[1462747581] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1000; }","duration":"180.755263ms","start":"2026-04-24T20:55:54.711555Z","end":"2026-04-24T20:55:54.89231Z","steps":["trace[1462747581] 'range keys from in-memory index tree' (duration: 180.62936ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:55:55.145313Z","caller":"traceutil/trace.go:171","msg":"trace[625676216] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"347.787037ms","start":"2026-04-24T20:55:54.7975Z","end":"2026-04-24T20:55:55.145287Z","steps":["trace[625676216] 'process raft request' (duration: 347.623213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:55:55.145634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:55:54.797477Z","time spent":"347.905931ms","remote":"127.0.0.1:36936","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-04-24T20:56:01.357228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.326176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T20:56:01.357419Z","caller":"traceutil/trace.go:171","msg":"trace[56405878] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"144.583429ms","start":"2026-04-24T20:56:01.212811Z","end":"2026-04-24T20:56:01.357395Z","steps":["trace[56405878] 'process raft request' (duration: 41.005046ms)","trace[56405878] 'compare' (duration: 103.195773ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T20:56:16.565812Z","caller":"traceutil/trace.go:171","msg":"trace[1591250476] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"159.330246ms","start":"2026-04-24T20:56:16.406463Z","end":"2026-04-24T20:56:16.565793Z","steps":["trace[1591250476] 'read index received' (duration: 159.163171ms)","trace[1591250476] 'applied index is now lower than readState.Index' (duration: 166.275µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T20:56:16.565947Z","caller":"traceutil/trace.go:171","msg":"trace[1107452001] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"450.411585ms","start":"2026-04-24T20:56:16.115528Z","end":"2026-04-24T20:56:16.56594Z","steps":["trace[1107452001] 'process raft request' (duration: 450.114317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:56:16.566042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:56:16.115506Z","time spent":"450.466596ms","remote":"127.0.0.1:36936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T20:56:16.566217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.738586ms","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-04-24T20:56:16.566274Z","caller":"traceutil/trace.go:171","msg":"trace[1046359013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"159.832219ms","start":"2026-04-24T20:56:16.406429Z","end":"2026-04-24T20:56:16.566262Z","steps":["trace[1046359013] 'agreement among raft nodes before linearized reading' (duration: 159.684635ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:56:34.948501Z","caller":"traceutil/trace.go:171","msg":"trace[1887495590] linearizableReadLoop","detail":"{readStateIndex:1228; appliedIndex:1227; }","duration":"280.095112ms","start":"2026-04-24T20:56:34.668383Z","end":"2026-04-24T20:56:34.948478Z","steps":["trace[1887495590] 'read index received' (duration: 279.907787ms)","trace[1887495590] 'applied index is now lower than readState.Index' (duration: 186.545µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T20:56:34.948826Z","caller":"traceutil/trace.go:171","msg":"trace[246711964] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"292.478816ms","start":"2026-04-24T20:56:34.656334Z","end":"2026-04-24T20:56:34.948813Z","steps":["trace[246711964] 'process raft request' (duration: 292.002703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:56:34.949025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.612966ms","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-04-24T20:56:34.949156Z","caller":"traceutil/trace.go:171","msg":"trace[859291553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"280.794551ms","start":"2026-04-24T20:56:34.668328Z","end":"2026-04-24T20:56:34.949123Z","steps":["trace[859291553] 'agreement among raft nodes before linearized reading' (duration: 280.495043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:56:34.949056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.941812ms","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-04-24T20:56:34.949382Z","caller":"traceutil/trace.go:171","msg":"trace[1368764675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1181; }","duration":"160.324642ms","start":"2026-04-24T20:56:34.789037Z","end":"2026-04-24T20:56:34.949362Z","steps":["trace[1368764675] 'agreement among raft nodes before linearized reading' (duration: 159.766667ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:56:35.152083Z","caller":"traceutil/trace.go:171","msg":"trace[2112054754] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"198.010569ms","start":"2026-04-24T20:56:34.954057Z","end":"2026-04-24T20:56:35.152068Z","steps":["trace[2112054754] 'process raft request' (duration: 197.922276ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:56:35.152847Z","caller":"traceutil/trace.go:171","msg":"trace[1340692901] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"198.515502ms","start":"2026-04-24T20:56:34.954323Z","end":"2026-04-24T20:56:35.152838Z","steps":["trace[1340692901] 'process raft request' (duration: 198.467501ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:56:52.550701Z","caller":"traceutil/trace.go:171","msg":"trace[204250884] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"116.345169ms","start":"2026-04-24T20:56:52.434312Z","end":"2026-04-24T20:56:52.550657Z","steps":["trace[204250884] 'process raft request' (duration: 116.147314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:56:52.838005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.80381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-24T20:56:52.838122Z","caller":"traceutil/trace.go:171","msg":"trace[842205826] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"279.316256ms","start":"2026-04-24T20:56:52.558783Z","end":"2026-04-24T20:56:52.838099Z","steps":["trace[842205826] 'process raft request' (duration: 133.347102ms)","trace[842205826] 'compare' (duration: 145.695797ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T20:56:52.843945Z","caller":"traceutil/trace.go:171","msg":"trace[172214391] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"275.471921ms","start":"2026-04-24T20:56:52.568445Z","end":"2026-04-24T20:56:52.843917Z","steps":["trace[172214391] 'process raft request' (duration: 275.367428ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:56:52.891882Z","caller":"traceutil/trace.go:171","msg":"trace[2022674121] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"132.756205ms","start":"2026-04-24T20:56:52.759082Z","end":"2026-04-24T20:56:52.891838Z","steps":["trace[2022674121] 'process raft request' (duration: 132.54838ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:56:53.461691Z","caller":"traceutil/trace.go:171","msg":"trace[1861401834] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"160.426367ms","start":"2026-04-24T20:56:53.301242Z","end":"2026-04-24T20:56:53.461669Z","steps":["trace[1861401834] 'process raft request' (duration: 160.250622ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:56:53.486536Z","caller":"traceutil/trace.go:171","msg":"trace[1922898821] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"184.836299ms","start":"2026-04-24T20:56:53.301664Z","end":"2026-04-24T20:56:53.4865Z","steps":["trace[1922898821] 'process raft request' (duration: 184.586013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:56:59.835615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.874099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-24T20:56:59.835695Z","caller":"traceutil/trace.go:171","msg":"trace[1032942472] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"112.001402ms","start":"2026-04-24T20:56:59.723677Z","end":"2026-04-24T20:56:59.835678Z","steps":["trace[1032942472] 'range keys from in-memory index tree' (duration: 111.715544ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:57:00.190431Z","caller":"traceutil/trace.go:171","msg":"trace[864822756] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"335.807639ms","start":"2026-04-24T20:56:59.8546Z","end":"2026-04-24T20:57:00.190407Z","steps":["trace[864822756] 'read index received' (duration: 240.501951ms)","trace[864822756] 'applied index is now lower than readState.Index' (duration: 95.304548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T20:57:00.190684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.016776ms","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-04-24T20:57:00.190713Z","caller":"traceutil/trace.go:171","msg":"trace[1137437781] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"350.605911ms","start":"2026-04-24T20:56:59.840059Z","end":"2026-04-24T20:57:00.190665Z","steps":["trace[1137437781] 'process raft request' (duration: 254.990154ms)","trace[1137437781] 'compare' (duration: 95.243587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T20:57:00.190894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:56:59.840038Z","time spent":"350.764356ms","remote":"127.0.0.1:36936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T20:57:00.19076Z","caller":"traceutil/trace.go:171","msg":"trace[988872889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1332; }","duration":"336.168369ms","start":"2026-04-24T20:56:59.85458Z","end":"2026-04-24T20:57:00.190749Z","steps":["trace[988872889] 'agreement among raft nodes before linearized reading' (duration: 335.929573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:57:00.191109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:56:59.854571Z","time spent":"336.437797ms","remote":"127.0.0.1:36936","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-04-24T20:59:22.529087Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310912774338961,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T20:59:22.736335Z","caller":"traceutil/trace.go:171","msg":"trace[395487745] linearizableReadLoop","detail":"{readStateIndex:2061; appliedIndex:2060; }","duration":"707.42939ms","start":"2026-04-24T20:59:22.028878Z","end":"2026-04-24T20:59:22.736307Z","steps":["trace[395487745] 'read index received' (duration: 707.183114ms)","trace[395487745] 'applied index is now lower than readState.Index' (duration: 244.996µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T20:59:22.736375Z","caller":"traceutil/trace.go:171","msg":"trace[1671450369] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"760.911537ms","start":"2026-04-24T20:59:21.975441Z","end":"2026-04-24T20:59:22.736352Z","steps":["trace[1671450369] 'process raft request' (duration: 760.709951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:59:22.736504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:59:21.975419Z","time spent":"761.01128ms","remote":"127.0.0.1:36936","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-04-24T20:59:22.736577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.143878ms","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-04-24T20:59:22.736748Z","caller":"traceutil/trace.go:171","msg":"trace[1712841508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1981; }","duration":"613.235381ms","start":"2026-04-24T20:59:22.12339Z","end":"2026-04-24T20:59:22.736625Z","steps":["trace[1712841508] 'agreement among raft nodes before linearized reading' (duration: 613.075367ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:59:22.736808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:59:22.123377Z","time spent":"613.416787ms","remote":"127.0.0.1:36936","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-04-24T20:59:22.736787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.677668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-24T20:59:22.7368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.097663ms","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-04-24T20:59:22.736877Z","caller":"traceutil/trace.go:171","msg":"trace[2017526503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1981; }","duration":"708.009737ms","start":"2026-04-24T20:59:22.028846Z","end":"2026-04-24T20:59:22.736856Z","steps":["trace[2017526503] 'agreement among raft nodes before linearized reading' (duration: 707.592255ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T20:59:22.73691Z","caller":"traceutil/trace.go:171","msg":"trace[366042893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1981; }","duration":"474.257827ms","start":"2026-04-24T20:59:22.262629Z","end":"2026-04-24T20:59:22.736887Z","steps":["trace[366042893] 'agreement among raft nodes before linearized reading' (duration: 473.857445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:59:22.736958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:59:22.26261Z","time spent":"474.33798ms","remote":"127.0.0.1:36936","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-04-24T20:59:22.736959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.379211ms","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-04-24T20:59:22.737007Z","caller":"traceutil/trace.go:171","msg":"trace[1491159569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1981; }","duration":"262.472023ms","start":"2026-04-24T20:59:22.474523Z","end":"2026-04-24T20:59:22.736995Z","steps":["trace[1491159569] 'agreement among raft nodes before linearized reading' (duration: 262.389761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:59:22.736938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:59:22.028833Z","time spent":"708.08822ms","remote":"127.0.0.1:36696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-24T20:59:22.737319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.8811ms","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-04-24T20:59:22.737465Z","caller":"traceutil/trace.go:171","msg":"trace[44173040] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1981; }","duration":"610.067315ms","start":"2026-04-24T20:59:22.12737Z","end":"2026-04-24T20:59:22.737438Z","steps":["trace[44173040] 'agreement among raft nodes before linearized reading' (duration: 609.365516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:59:22.737562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:59:22.127364Z","time spent":"610.178448ms","remote":"127.0.0.1:36936","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-04-24T20:59:22.738446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.492253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-04-24T20:59:22.738535Z","caller":"traceutil/trace.go:171","msg":"trace[1570175368] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1981; }","duration":"599.628357ms","start":"2026-04-24T20:59:22.138882Z","end":"2026-04-24T20:59:22.738511Z","steps":["trace[1570175368] 'agreement among raft nodes before linearized reading' (duration: 597.674432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T20:59:22.738593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T20:59:22.13887Z","time spent":"599.707838ms","remote":"127.0.0.1:36758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050283,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-24T21:00:03.432132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310912774339471,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T21:00:03.932589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310912774339471,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T21:00:04.018793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159408245s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-24T21:00:04.307493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.338875206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-24T21:00:04.307613Z","caller":"traceutil/trace.go:171","msg":"trace[1993114310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2185; }","duration":"2.33904003s","start":"2026-04-24T21:00:01.968548Z","end":"2026-04-24T21:00:04.307588Z","steps":["trace[1993114310] 'range keys from in-memory index tree' (duration: 2.338703532s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.307627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.451855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-04-24T21:00:04.307694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:01.968529Z","time spent":"2.339146744s","remote":"127.0.0.1:36936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-24T21:00:04.307835Z","caller":"traceutil/trace.go:171","msg":"trace[14728396] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"1.448558478s","start":"2026-04-24T21:00:02.859254Z","end":"2026-04-24T21:00:04.307813Z","steps":["trace[14728396] 'process raft request' (duration: 1.159828376s)","trace[14728396] 'compare' (duration: 288.020303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T21:00:04.308036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:02.859228Z","time spent":"1.448716413s","remote":"127.0.0.1:36936","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-04-24T21:00:04.310881Z","caller":"traceutil/trace.go:171","msg":"trace[555459376] linearizableReadLoop","detail":"{readStateIndex:2276; appliedIndex:2274; }","duration":"1.379267823s","start":"2026-04-24T21:00:02.931599Z","end":"2026-04-24T21:00:04.310867Z","steps":["trace[555459376] 'read index received' (duration: 1.087488026s)","trace[555459376] 'applied index is now lower than readState.Index' (duration: 291.777797ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T21:00:04.31099Z","caller":"traceutil/trace.go:171","msg":"trace[1381283717] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"160.746936ms","start":"2026-04-24T21:00:04.150155Z","end":"2026-04-24T21:00:04.310902Z","steps":["trace[1381283717] 'process raft request' (duration: 160.639913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.311124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.318634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T21:00:04.311167Z","caller":"traceutil/trace.go:171","msg":"trace[129628779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2187; }","duration":"842.384346ms","start":"2026-04-24T21:00:03.46877Z","end":"2026-04-24T21:00:04.311154Z","steps":["trace[129628779] 'agreement among raft nodes before linearized reading' (duration: 842.322034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.311193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:03.468756Z","time spent":"842.430327ms","remote":"127.0.0.1:36642","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-04-24T21:00:04.311248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377330269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-24T21:00:04.311331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132793754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T21:00:04.311371Z","caller":"traceutil/trace.go:171","msg":"trace[849376896] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2187; }","duration":"1.132882647s","start":"2026-04-24T21:00:03.178477Z","end":"2026-04-24T21:00:04.311359Z","steps":["trace[849376896] 'agreement among raft nodes before linearized reading' (duration: 1.132680901s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.311371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.416186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2721"} {"level":"info","ts":"2026-04-24T21:00:04.31137Z","caller":"traceutil/trace.go:171","msg":"trace[56913304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2187; }","duration":"1.377472572s","start":"2026-04-24T21:00:02.933865Z","end":"2026-04-24T21:00:04.311338Z","steps":["trace[56913304] 'agreement among raft nodes before linearized reading' (duration: 1.377163723s)"],"step_count":1} {"level":"info","ts":"2026-04-24T21:00:04.311399Z","caller":"traceutil/trace.go:171","msg":"trace[592613537] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2187; }","duration":"156.485577ms","start":"2026-04-24T21:00:04.154906Z","end":"2026-04-24T21:00:04.311391Z","steps":["trace[592613537] 'agreement among raft nodes before linearized reading' (duration: 156.401135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.311415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:02.93383Z","time spent":"1.377576165s","remote":"127.0.0.1:36936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-24T21:00:04.311566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.888231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-04-24T21:00:04.311567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.295168946s","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-04-24T21:00:04.311597Z","caller":"traceutil/trace.go:171","msg":"trace[502085253] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2187; }","duration":"245.971243ms","start":"2026-04-24T21:00:04.065618Z","end":"2026-04-24T21:00:04.311589Z","steps":["trace[502085253] 'agreement among raft nodes before linearized reading' (duration: 245.886531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.3116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375807847s","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-04-24T21:00:04.311616Z","caller":"traceutil/trace.go:171","msg":"trace[1740645050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2187; }","duration":"1.295250698s","start":"2026-04-24T21:00:03.016356Z","end":"2026-04-24T21:00:04.311607Z","steps":["trace[1740645050] 'agreement among raft nodes before linearized reading' (duration: 1.295110854s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.311645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:03.016343Z","time spent":"1.295296859s","remote":"127.0.0.1:36936","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-04-24T21:00:04.311666Z","caller":"traceutil/trace.go:171","msg":"trace[2059928756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2187; }","duration":"1.375871748s","start":"2026-04-24T21:00:02.935762Z","end":"2026-04-24T21:00:04.311634Z","steps":["trace[2059928756] 'agreement among raft nodes before linearized reading' (duration: 1.375767465s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.311703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.222426ms","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":"warn","ts":"2026-04-24T21:00:04.311701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:02.935749Z","time spent":"1.375944981s","remote":"127.0.0.1:36936","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-04-24T21:00:04.311733Z","caller":"traceutil/trace.go:171","msg":"trace[512919993] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2187; }","duration":"537.285448ms","start":"2026-04-24T21:00:03.77444Z","end":"2026-04-24T21:00:04.311726Z","steps":["trace[512919993] 'agreement among raft nodes before linearized reading' (duration: 537.210586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.311757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:03.774422Z","time spent":"537.329029ms","remote":"127.0.0.1:36936","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\" "} {"level":"warn","ts":"2026-04-24T21:00:04.311887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038313469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-24T21:00:04.311971Z","caller":"traceutil/trace.go:171","msg":"trace[912621752] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2187; }","duration":"1.038391911s","start":"2026-04-24T21:00:03.273563Z","end":"2026-04-24T21:00:04.311955Z","steps":["trace[912621752] 'agreement among raft nodes before linearized reading' (duration: 1.038252127s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.312006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:03.27351Z","time spent":"1.038485383s","remote":"127.0.0.1:36936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-24T21:00:04.312067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380463457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-24T21:00:04.312786Z","caller":"traceutil/trace.go:171","msg":"trace[691947448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2187; }","duration":"1.381215557s","start":"2026-04-24T21:00:02.931557Z","end":"2026-04-24T21:00:04.312772Z","steps":["trace[691947448] 'agreement among raft nodes before linearized reading' (duration: 1.379485769s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T21:00:04.312834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:02.931537Z","time spent":"1.381284469s","remote":"127.0.0.1:36936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T21:00:04.312185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T21:00:03.178455Z","time spent":"1.132936268s","remote":"127.0.0.1:36870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-24T21:00:16.220089Z","caller":"traceutil/trace.go:171","msg":"trace[1615180972] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"108.605378ms","start":"2026-04-24T21:00:16.111463Z","end":"2026-04-24T21:00:16.220068Z","steps":["trace[1615180972] 'process raft request' (duration: 108.450173ms)"],"step_count":1}