{"level":"warn","ts":"2026-07-17T05:02:53.709833Z","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-17T05:02:53.709918Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-17T05:02:53.709984Z","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-17T05:02:53.709996Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-17T05:02:53.710017Z","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-17T05:02:53.71062Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-07-17T05:02:53.710699Z","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":16,"max-cpu-available":16,"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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-17T05:02:53.714732Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.837514ms"} {"level":"info","ts":"2026-07-17T05:02:53.722273Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-07-17T05:02:53.722363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T05:02:53.722393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-07-17T05:02:53.722404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T05:02:53.722416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-07-17T05:02:53.722453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-07-17T05:02:53.72929Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T05:02:53.731326Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T05:02:53.732739Z","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-17T05:02:53.735129Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T05:02:53.735769Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T05:02:53.735877Z","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-17T05:02:53.735962Z","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-17T05:02:53.735975Z","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-17T05:02:53.740173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-07-17T05:02:53.740328Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-07-17T05:02:53.741299Z","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-17T05:02:53.741407Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-17T05:02:53.741448Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-07-17T05:02:53.741748Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T05:02:53.741791Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T05:02:54.723008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T05:02:54.723071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T05:02:54.723102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-07-17T05:02:54.723118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-07-17T05:02:54.723127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-17T05:02:54.723143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-07-17T05:02:54.723153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-07-17T05:02:54.724128Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T05:02:54.724137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T05:02:54.72416Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:02:54.724221Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T05:02:54.72435Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T05:02:54.724477Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T05:02:54.725182Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:02:54.725366Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:02:54.725419Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:02:54.728205Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-07-17T05:02:54.728647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T05:03:22.202413Z","caller":"traceutil/trace.go:171","msg":"trace[15642894] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"151.323591ms","start":"2026-07-17T05:03:22.051064Z","end":"2026-07-17T05:03:22.202387Z","steps":["trace[15642894] 'read index received' (duration: 151.068464ms)","trace[15642894] 'applied index is now lower than readState.Index' (duration: 254.217µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:03:22.202568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.484774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:03:22.202658Z","caller":"traceutil/trace.go:171","msg":"trace[829360959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"151.637808ms","start":"2026-07-17T05:03:22.051008Z","end":"2026-07-17T05:03:22.202646Z","steps":["trace[829360959] 'agreement among raft nodes before linearized reading' (duration: 151.499905ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:03:22.202538Z","caller":"traceutil/trace.go:171","msg":"trace[1973170393] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"192.105004ms","start":"2026-07-17T05:03:22.01039Z","end":"2026-07-17T05:03:22.202495Z","steps":["trace[1973170393] 'process raft request' (duration: 191.856088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:03:30.58323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.652913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T05:03:30.583783Z","caller":"traceutil/trace.go:171","msg":"trace[359520054] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"250.347047ms","start":"2026-07-17T05:03:30.333152Z","end":"2026-07-17T05:03:30.583499Z","steps":["trace[359520054] 'process raft request' (duration: 129.269999ms)","trace[359520054] 'compare' (duration: 119.467738ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:03:30.589882Z","caller":"traceutil/trace.go:171","msg":"trace[1800542395] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"196.4558ms","start":"2026-07-17T05:03:30.393387Z","end":"2026-07-17T05:03:30.589843Z","steps":["trace[1800542395] 'process raft request' (duration: 196.194994ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:03:30.589993Z","caller":"traceutil/trace.go:171","msg":"trace[2091641053] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"210.304361ms","start":"2026-07-17T05:03:30.379657Z","end":"2026-07-17T05:03:30.589961Z","steps":["trace[2091641053] 'process raft request' (duration: 209.84765ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:03:30.590038Z","caller":"traceutil/trace.go:171","msg":"trace[19775190] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"251.940915ms","start":"2026-07-17T05:03:30.338057Z","end":"2026-07-17T05:03:30.589998Z","steps":["trace[19775190] 'process raft request' (duration: 251.349101ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:03:36.538174Z","caller":"traceutil/trace.go:171","msg":"trace[1072524723] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"234.524677ms","start":"2026-07-17T05:03:36.301522Z","end":"2026-07-17T05:03:36.536047Z","steps":["trace[1072524723] 'process raft request' (duration: 150.760387ms)","trace[1072524723] 'compare' (duration: 81.669688ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:03:36.789581Z","caller":"traceutil/trace.go:171","msg":"trace[1512282786] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"171.286941ms","start":"2026-07-17T05:03:36.61827Z","end":"2026-07-17T05:03:36.789557Z","steps":["trace[1512282786] 'read index received' (duration: 124.46886ms)","trace[1512282786] 'applied index is now lower than readState.Index' (duration: 46.817141ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:03:36.789943Z","caller":"traceutil/trace.go:171","msg":"trace[1811784695] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"200.10066ms","start":"2026-07-17T05:03:36.589785Z","end":"2026-07-17T05:03:36.789885Z","steps":["trace[1811784695] 'process raft request' (duration: 153.002942ms)","trace[1811784695] 'compare' (duration: 46.654607ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:03:36.790024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.740293ms","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-17T05:03:36.7901Z","caller":"traceutil/trace.go:171","msg":"trace[1646945049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:629; }","duration":"171.875686ms","start":"2026-07-17T05:03:36.618204Z","end":"2026-07-17T05:03:36.79008Z","steps":["trace[1646945049] 'agreement among raft nodes before linearized reading' (duration: 171.670181ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:03:37.28131Z","caller":"traceutil/trace.go:171","msg":"trace[1925551719] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"438.196156ms","start":"2026-07-17T05:03:36.84309Z","end":"2026-07-17T05:03:37.281286Z","steps":["trace[1925551719] 'process raft request' (duration: 389.463417ms)","trace[1925551719] 'compare' (duration: 48.600516ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:03:37.281443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:03:36.843075Z","time spent":"438.296998ms","remote":"127.0.0.1:57608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:03:37.481659Z","caller":"traceutil/trace.go:171","msg":"trace[166588391] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"582.455183ms","start":"2026-07-17T05:03:36.899158Z","end":"2026-07-17T05:03:37.481613Z","steps":["trace[166588391] 'process raft request' (duration: 582.135835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:03:37.481838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:03:36.899142Z","time spent":"582.600286ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:03:37.482047Z","caller":"traceutil/trace.go:171","msg":"trace[2057582984] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"432.497655ms","start":"2026-07-17T05:03:37.049528Z","end":"2026-07-17T05:03:37.482025Z","steps":["trace[2057582984] 'read index received' (duration: 183.103202ms)","trace[2057582984] 'applied index is now lower than readState.Index' (duration: 249.393603ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:03:37.482121Z","caller":"traceutil/trace.go:171","msg":"trace[1996091951] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"255.638706ms","start":"2026-07-17T05:03:37.22644Z","end":"2026-07-17T05:03:37.482078Z","steps":["trace[1996091951] 'process raft request' (duration: 255.171915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:03:37.482154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.616268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:03:37.482196Z","caller":"traceutil/trace.go:171","msg":"trace[1826876804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"432.69783ms","start":"2026-07-17T05:03:37.049485Z","end":"2026-07-17T05:03:37.482183Z","steps":["trace[1826876804] 'agreement among raft nodes before linearized reading' (duration: 432.617698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:03:37.482238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:03:37.049466Z","time spent":"432.765071ms","remote":"127.0.0.1:57394","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-17T05:03:37.482335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.288947ms","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-17T05:03:37.482405Z","caller":"traceutil/trace.go:171","msg":"trace[592742831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"281.4071ms","start":"2026-07-17T05:03:37.200983Z","end":"2026-07-17T05:03:37.48239Z","steps":["trace[592742831] 'agreement among raft nodes before linearized reading' (duration: 281.210165ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:03:54.618921Z","caller":"traceutil/trace.go:171","msg":"trace[999832235] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"236.741342ms","start":"2026-07-17T05:03:54.382158Z","end":"2026-07-17T05:03:54.618899Z","steps":["trace[999832235] 'process raft request' (duration: 236.583188ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:05.605207Z","caller":"traceutil/trace.go:171","msg":"trace[1283933315] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"335.669855ms","start":"2026-07-17T05:05:05.269509Z","end":"2026-07-17T05:05:05.605179Z","steps":["trace[1283933315] 'read index received' (duration: 335.481441ms)","trace[1283933315] 'applied index is now lower than readState.Index' (duration: 187.254µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:05.605347Z","caller":"traceutil/trace.go:171","msg":"trace[633687147] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"336.776683ms","start":"2026-07-17T05:05:05.268558Z","end":"2026-07-17T05:05:05.605335Z","steps":["trace[633687147] 'process raft request' (duration: 336.467165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:05.605466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:05.268531Z","time spent":"336.844075ms","remote":"127.0.0.1:57608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:05:05.605723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.42118ms","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-17T05:05:05.605809Z","caller":"traceutil/trace.go:171","msg":"trace[1201637524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1854; }","duration":"257.543983ms","start":"2026-07-17T05:05:05.348247Z","end":"2026-07-17T05:05:05.605791Z","steps":["trace[1201637524] 'agreement among raft nodes before linearized reading' (duration: 257.324667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:05.605795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.102142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-17T05:05:05.605895Z","caller":"traceutil/trace.go:171","msg":"trace[48568737] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1854; }","duration":"200.255635ms","start":"2026-07-17T05:05:05.405622Z","end":"2026-07-17T05:05:05.605878Z","steps":["trace[48568737] 'agreement among raft nodes before linearized reading' (duration: 200.012998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:05.60586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.308342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0bbfad3-b495-401f-ad4c-4dcf7a9ca873\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-07-17T05:05:05.605996Z","caller":"traceutil/trace.go:171","msg":"trace[1489245640] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0bbfad3-b495-401f-ad4c-4dcf7a9ca873; range_end:; response_count:1; response_revision:1854; }","duration":"336.486297ms","start":"2026-07-17T05:05:05.269478Z","end":"2026-07-17T05:05:05.605964Z","steps":["trace[1489245640] 'agreement among raft nodes before linearized reading' (duration: 336.20571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:05.60604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.164995ms","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-17T05:05:05.606135Z","caller":"traceutil/trace.go:171","msg":"trace[1793475102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1854; }","duration":"251.294768ms","start":"2026-07-17T05:05:05.354816Z","end":"2026-07-17T05:05:05.606111Z","steps":["trace[1793475102] 'agreement among raft nodes before linearized reading' (duration: 250.928269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:05.606063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:05.269469Z","time spent":"336.582169ms","remote":"127.0.0.1:57608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3767,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0bbfad3-b495-401f-ad4c-4dcf7a9ca873\" "} {"level":"info","ts":"2026-07-17T05:05:12.454182Z","caller":"traceutil/trace.go:171","msg":"trace[940703679] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"130.887919ms","start":"2026-07-17T05:05:12.323268Z","end":"2026-07-17T05:05:12.454156Z","steps":["trace[940703679] 'read index received' (duration: 87.703324ms)","trace[940703679] 'applied index is now lower than readState.Index' (duration: 43.183485ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:12.454217Z","caller":"traceutil/trace.go:171","msg":"trace[122936305] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"145.068501ms","start":"2026-07-17T05:05:12.309121Z","end":"2026-07-17T05:05:12.45419Z","steps":["trace[122936305] 'process raft request' (duration: 101.695832ms)","trace[122936305] 'compare' (duration: 43.188185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:05:12.454395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.106055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-07-17T05:05:12.45444Z","caller":"traceutil/trace.go:171","msg":"trace[14915011] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1928; }","duration":"131.208517ms","start":"2026-07-17T05:05:12.323217Z","end":"2026-07-17T05:05:12.454425Z","steps":["trace[14915011] 'agreement among raft nodes before linearized reading' (duration: 131.037203ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:12.455508Z","caller":"traceutil/trace.go:171","msg":"trace[281218757] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"117.502015ms","start":"2026-07-17T05:05:12.337979Z","end":"2026-07-17T05:05:12.455481Z","steps":["trace[281218757] 'process raft request' (duration: 117.349141ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:17.950547Z","caller":"traceutil/trace.go:171","msg":"trace[1390933823] linearizableReadLoop","detail":"{readStateIndex:2040; appliedIndex:2039; }","duration":"194.795689ms","start":"2026-07-17T05:05:17.755729Z","end":"2026-07-17T05:05:17.950525Z","steps":["trace[1390933823] 'read index received' (duration: 194.729748ms)","trace[1390933823] 'applied index is now lower than readState.Index' (duration: 64.701µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:17.950685Z","caller":"traceutil/trace.go:171","msg":"trace[917298023] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"248.134517ms","start":"2026-07-17T05:05:17.702517Z","end":"2026-07-17T05:05:17.950652Z","steps":["trace[917298023] 'process raft request' (duration: 247.857961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:17.950814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.057916ms","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-17T05:05:17.950885Z","caller":"traceutil/trace.go:171","msg":"trace[106616003] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1959; }","duration":"195.191509ms","start":"2026-07-17T05:05:17.755676Z","end":"2026-07-17T05:05:17.950868Z","steps":["trace[106616003] 'agreement among raft nodes before linearized reading' (duration: 194.997064ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:17.986904Z","caller":"traceutil/trace.go:171","msg":"trace[417549828] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"172.270758ms","start":"2026-07-17T05:05:17.814598Z","end":"2026-07-17T05:05:17.986869Z","steps":["trace[417549828] 'process raft request' (duration: 170.465164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:17.987205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.766199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T05:05:17.98727Z","caller":"traceutil/trace.go:171","msg":"trace[1841870397] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:1961; }","duration":"186.859081ms","start":"2026-07-17T05:05:17.800394Z","end":"2026-07-17T05:05:17.987253Z","steps":["trace[1841870397] 'agreement among raft nodes before linearized reading' (duration: 186.478812ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:49.304988Z","caller":"traceutil/trace.go:171","msg":"trace[1986058006] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"105.844776ms","start":"2026-07-17T05:05:49.199121Z","end":"2026-07-17T05:05:49.304966Z","steps":["trace[1986058006] 'process raft request' (duration: 105.676411ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:49.307905Z","caller":"traceutil/trace.go:171","msg":"trace[1006128745] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"106.366528ms","start":"2026-07-17T05:05:49.201504Z","end":"2026-07-17T05:05:49.307871Z","steps":["trace[1006128745] 'process raft request' (duration: 106.172223ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:55.615371Z","caller":"traceutil/trace.go:171","msg":"trace[828448107] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"280.175385ms","start":"2026-07-17T05:05:55.335167Z","end":"2026-07-17T05:05:55.615343Z","steps":["trace[828448107] 'process raft request' (duration: 280.022561ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:55.624951Z","caller":"traceutil/trace.go:171","msg":"trace[1797101353] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"289.538569ms","start":"2026-07-17T05:05:55.335385Z","end":"2026-07-17T05:05:55.624924Z","steps":["trace[1797101353] 'process raft request' (duration: 289.213381ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:56.332794Z","caller":"traceutil/trace.go:171","msg":"trace[1339034009] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2268; }","duration":"180.700798ms","start":"2026-07-17T05:05:56.152052Z","end":"2026-07-17T05:05:56.332753Z","steps":["trace[1339034009] 'read index received' (duration: 180.425531ms)","trace[1339034009] 'applied index is now lower than readState.Index' (duration: 273.727µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:56.332827Z","caller":"traceutil/trace.go:171","msg":"trace[628872797] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"186.40542ms","start":"2026-07-17T05:05:56.146386Z","end":"2026-07-17T05:05:56.332792Z","steps":["trace[628872797] 'process raft request' (duration: 186.128063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:56.333145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.02557ms","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-17T05:05:56.333198Z","caller":"traceutil/trace.go:171","msg":"trace[1711305198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2180; }","duration":"147.130703ms","start":"2026-07-17T05:05:56.186049Z","end":"2026-07-17T05:05:56.33318Z","steps":["trace[1711305198] 'agreement among raft nodes before linearized reading' (duration: 146.917097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:56.333304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.13653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T05:05:56.333444Z","caller":"traceutil/trace.go:171","msg":"trace[1809757127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2180; }","duration":"181.366586ms","start":"2026-07-17T05:05:56.152036Z","end":"2026-07-17T05:05:56.333402Z","steps":["trace[1809757127] 'agreement among raft nodes before linearized reading' (duration: 180.840532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:56.61329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.431742ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T05:05:56.613414Z","caller":"traceutil/trace.go:171","msg":"trace[480773722] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"273.535841ms","start":"2026-07-17T05:05:56.339855Z","end":"2026-07-17T05:05:56.613391Z","steps":["trace[480773722] 'process raft request' (duration: 143.895742ms)","trace[480773722] 'compare' (duration: 129.239188ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:56.629637Z","caller":"traceutil/trace.go:171","msg":"trace[1977930857] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2270; }","duration":"113.184197ms","start":"2026-07-17T05:05:56.516426Z","end":"2026-07-17T05:05:56.62961Z","steps":["trace[1977930857] 'read index received' (duration: 113.052534ms)","trace[1977930857] 'applied index is now lower than readState.Index' (duration: 130.713µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:56.629676Z","caller":"traceutil/trace.go:171","msg":"trace[773322691] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"289.563018ms","start":"2026-07-17T05:05:56.340087Z","end":"2026-07-17T05:05:56.62965Z","steps":["trace[773322691] 'process raft request' (duration: 289.444426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:56.629817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.340682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:05:56.629868Z","caller":"traceutil/trace.go:171","msg":"trace[1382291696] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"256.28953ms","start":"2026-07-17T05:05:56.373561Z","end":"2026-07-17T05:05:56.629851Z","steps":["trace[1382291696] 'process raft request' (duration: 256.012014ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:56.629921Z","caller":"traceutil/trace.go:171","msg":"trace[758974311] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2184; }","duration":"113.535326ms","start":"2026-07-17T05:05:56.516343Z","end":"2026-07-17T05:05:56.629878Z","steps":["trace[758974311] 'agreement among raft nodes before linearized reading' (duration: 113.368642ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:56.629783Z","caller":"traceutil/trace.go:171","msg":"trace[871424614] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"289.733932ms","start":"2026-07-17T05:05:56.339999Z","end":"2026-07-17T05:05:56.629733Z","steps":["trace[871424614] 'process raft request' (duration: 289.416825ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:56.191052Z","caller":"traceutil/trace.go:171","msg":"trace[1342359106] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"177.199058ms","start":"2026-07-17T05:06:56.013821Z","end":"2026-07-17T05:06:56.19102Z","steps":["trace[1342359106] 'process raft request' (duration: 177.026964ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:56.277362Z","caller":"traceutil/trace.go:171","msg":"trace[1341618192] linearizableReadLoop","detail":"{readStateIndex:2902; appliedIndex:2900; }","duration":"227.757871ms","start":"2026-07-17T05:06:56.049583Z","end":"2026-07-17T05:06:56.277341Z","steps":["trace[1341618192] 'read index received' (duration: 141.196827ms)","trace[1341618192] 'applied index is now lower than readState.Index' (duration: 86.560264ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:56.277453Z","caller":"traceutil/trace.go:171","msg":"trace[589338111] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"262.860231ms","start":"2026-07-17T05:06:56.014582Z","end":"2026-07-17T05:06:56.277442Z","steps":["trace[589338111] 'process raft request' (duration: 245.962122ms)","trace[589338111] 'compare' (duration: 16.705554ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:06:56.277613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.999788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:56.277688Z","caller":"traceutil/trace.go:171","msg":"trace[1474141259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2789; }","duration":"228.11926ms","start":"2026-07-17T05:06:56.049554Z","end":"2026-07-17T05:06:56.277674Z","steps":["trace[1474141259] 'agreement among raft nodes before linearized reading' (duration: 227.947486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:56.307547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.161193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7287479b-a530-4ebc-b78a-1a6fb05364bf\" ","response":"range_response_count:1 size:3617"} {"level":"info","ts":"2026-07-17T05:06:56.307634Z","caller":"traceutil/trace.go:171","msg":"trace[1231249417] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7287479b-a530-4ebc-b78a-1a6fb05364bf; range_end:; response_count:1; response_revision:2790; }","duration":"157.296616ms","start":"2026-07-17T05:06:56.15032Z","end":"2026-07-17T05:06:56.307617Z","steps":["trace[1231249417] 'agreement among raft nodes before linearized reading' (duration: 157.075241ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:07:38.633486Z","caller":"traceutil/trace.go:171","msg":"trace[1431107853] transaction","detail":"{read_only:false; response_revision:3212; number_of_response:1; }","duration":"191.823285ms","start":"2026-07-17T05:07:38.441635Z","end":"2026-07-17T05:07:38.633458Z","steps":["trace[1431107853] 'process raft request' (duration: 191.640901ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:07:38.746311Z","caller":"traceutil/trace.go:171","msg":"trace[923140776] transaction","detail":"{read_only:false; response_revision:3213; number_of_response:1; }","duration":"285.806026ms","start":"2026-07-17T05:07:38.460475Z","end":"2026-07-17T05:07:38.746281Z","steps":["trace[923140776] 'process raft request' (duration: 230.953905ms)","trace[923140776] 'compare' (duration: 54.646146ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:07:38.746555Z","caller":"traceutil/trace.go:171","msg":"trace[1691670432] transaction","detail":"{read_only:false; response_revision:3214; number_of_response:1; }","duration":"145.658401ms","start":"2026-07-17T05:07:38.600858Z","end":"2026-07-17T05:07:38.746517Z","steps":["trace[1691670432] 'process raft request' (duration: 145.380294ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:07:38.746593Z","caller":"traceutil/trace.go:171","msg":"trace[1758783031] linearizableReadLoop","detail":"{readStateIndex:3341; appliedIndex:3339; }","duration":"266.341164ms","start":"2026-07-17T05:07:38.480222Z","end":"2026-07-17T05:07:38.746563Z","steps":["trace[1758783031] 'read index received' (duration: 152.999603ms)","trace[1758783031] 'applied index is now lower than readState.Index' (duration: 113.339931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:07:38.746864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.353767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2a5e7510-e7af-483e-b3ff-3bffb87d3761\" ","response":"range_response_count:1 size:3719"} {"level":"warn","ts":"2026-07-17T05:07:38.746868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.643611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T05:07:38.746911Z","caller":"traceutil/trace.go:171","msg":"trace[1918605979] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2a5e7510-e7af-483e-b3ff-3bffb87d3761; range_end:; response_count:1; response_revision:3215; }","duration":"248.43886ms","start":"2026-07-17T05:07:38.498459Z","end":"2026-07-17T05:07:38.746898Z","steps":["trace[1918605979] 'agreement among raft nodes before linearized reading' (duration: 248.262606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:07:38.746862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.342533ms","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-17T05:07:38.746987Z","caller":"traceutil/trace.go:171","msg":"trace[1877258076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3215; }","duration":"266.787504ms","start":"2026-07-17T05:07:38.480169Z","end":"2026-07-17T05:07:38.746957Z","steps":["trace[1877258076] 'agreement among raft nodes before linearized reading' (duration: 266.528188ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:07:38.747023Z","caller":"traceutil/trace.go:171","msg":"trace[950384121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3215; }","duration":"266.556089ms","start":"2026-07-17T05:07:38.480453Z","end":"2026-07-17T05:07:38.747009Z","steps":["trace[950384121] 'agreement among raft nodes before linearized reading' (duration: 266.264131ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:07:39.123253Z","caller":"traceutil/trace.go:171","msg":"trace[1477867035] transaction","detail":"{read_only:false; response_revision:3218; number_of_response:1; }","duration":"126.375413ms","start":"2026-07-17T05:07:38.996849Z","end":"2026-07-17T05:07:39.123224Z","steps":["trace[1477867035] 'process raft request' (duration: 82.767603ms)","trace[1477867035] 'compare' (duration: 43.487427ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:07:39.148369Z","caller":"traceutil/trace.go:171","msg":"trace[1434189624] transaction","detail":"{read_only:false; response_revision:3219; number_of_response:1; }","duration":"148.250596ms","start":"2026-07-17T05:07:39.000091Z","end":"2026-07-17T05:07:39.148342Z","steps":["trace[1434189624] 'process raft request' (duration: 148.075502ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:07:50.652413Z","caller":"traceutil/trace.go:171","msg":"trace[79000923] transaction","detail":"{read_only:false; response_revision:3312; number_of_response:1; }","duration":"122.406596ms","start":"2026-07-17T05:07:50.529983Z","end":"2026-07-17T05:07:50.65239Z","steps":["trace[79000923] 'process raft request' (duration: 122.259472ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:09:03.506377Z","caller":"traceutil/trace.go:171","msg":"trace[1012580271] transaction","detail":"{read_only:false; response_revision:4045; number_of_response:1; }","duration":"230.304314ms","start":"2026-07-17T05:09:03.276039Z","end":"2026-07-17T05:09:03.506343Z","steps":["trace[1012580271] 'process raft request' (duration: 222.291255ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:09:25.303263Z","caller":"traceutil/trace.go:171","msg":"trace[950279062] transaction","detail":"{read_only:false; response_revision:4198; number_of_response:1; }","duration":"107.292287ms","start":"2026-07-17T05:09:25.195947Z","end":"2026-07-17T05:09:25.303239Z","steps":["trace[950279062] 'process raft request' (duration: 107.145383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.642403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.417057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T05:09:25.642634Z","caller":"traceutil/trace.go:171","msg":"trace[832578166] linearizableReadLoop","detail":"{readStateIndex:4418; appliedIndex:4416; }","duration":"436.776639ms","start":"2026-07-17T05:09:25.20584Z","end":"2026-07-17T05:09:25.642617Z","steps":["trace[832578166] 'read index received' (duration: 97.244749ms)","trace[832578166] 'applied index is now lower than readState.Index' (duration: 339.53078ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:09:25.642718Z","caller":"traceutil/trace.go:171","msg":"trace[1833466204] transaction","detail":"{read_only:false; response_revision:4199; number_of_response:1; }","duration":"437.622449ms","start":"2026-07-17T05:09:25.205003Z","end":"2026-07-17T05:09:25.642626Z","steps":["trace[1833466204] 'process raft request' (duration: 246.891675ms)","trace[1833466204] 'compare' (duration: 189.876033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:09:25.642977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:09:25.204978Z","time spent":"437.868375ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":483,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:09:25.643119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.975433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:635"} {"level":"info","ts":"2026-07-17T05:09:25.643263Z","caller":"traceutil/trace.go:171","msg":"trace[1866368681] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:4200; }","duration":"437.443195ms","start":"2026-07-17T05:09:25.205801Z","end":"2026-07-17T05:09:25.643244Z","steps":["trace[1866368681] 'agreement among raft nodes before linearized reading' (duration: 436.909202ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:09:25.643205Z","caller":"traceutil/trace.go:171","msg":"trace[1701678218] transaction","detail":"{read_only:false; response_revision:4200; number_of_response:1; }","duration":"435.215189ms","start":"2026-07-17T05:09:25.207763Z","end":"2026-07-17T05:09:25.642978Z","steps":["trace[1701678218] 'process raft request' (duration: 434.754258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.643514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:09:25.205786Z","time spent":"437.713841ms","remote":"127.0.0.1:57770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":658,"request content":"key:\"/registry/clusterroles/system:node-bootstrapper\" "} {"level":"warn","ts":"2026-07-17T05:09:25.643734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:09:25.207748Z","time spent":"435.837244ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:09:25.803917Z","caller":"traceutil/trace.go:171","msg":"trace[2097500345] transaction","detail":"{read_only:false; response_revision:4201; number_of_response:1; }","duration":"210.698819ms","start":"2026-07-17T05:09:25.593187Z","end":"2026-07-17T05:09:25.803886Z","steps":["trace[2097500345] 'process raft request' (duration: 154.338124ms)","trace[2097500345] 'compare' (duration: 56.216342ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:09:25.807412Z","caller":"traceutil/trace.go:171","msg":"trace[593169768] transaction","detail":"{read_only:false; response_revision:4202; number_of_response:1; }","duration":"212.688978ms","start":"2026-07-17T05:09:25.594701Z","end":"2026-07-17T05:09:25.80739Z","steps":["trace[593169768] 'process raft request' (duration: 212.495933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.807533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.776927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-07-17T05:09:25.807585Z","caller":"traceutil/trace.go:171","msg":"trace[1375430640] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:4203; }","duration":"591.86719ms","start":"2026-07-17T05:09:25.215707Z","end":"2026-07-17T05:09:25.807574Z","steps":["trace[1375430640] 'agreement among raft nodes before linearized reading' (duration: 591.753057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.80763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:09:25.215699Z","time spent":"591.921981ms","remote":"127.0.0.1:57770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":678,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"info","ts":"2026-07-17T05:09:25.807655Z","caller":"traceutil/trace.go:171","msg":"trace[1038288724] transaction","detail":"{read_only:false; response_revision:4203; number_of_response:1; }","duration":"211.971401ms","start":"2026-07-17T05:09:25.595657Z","end":"2026-07-17T05:09:25.807628Z","steps":["trace[1038288724] 'process raft request' (duration: 211.673204ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:09:25.807398Z","caller":"traceutil/trace.go:171","msg":"trace[776933514] linearizableReadLoop","detail":"{readStateIndex:4422; appliedIndex:4419; }","duration":"164.70826ms","start":"2026-07-17T05:09:25.642669Z","end":"2026-07-17T05:09:25.807378Z","steps":["trace[776933514] 'read index received' (duration: 104.871878ms)","trace[776933514] 'applied index is now lower than readState.Index' (duration: 59.835262ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:09:25.807552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.348035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:09:25.807841Z","caller":"traceutil/trace.go:171","msg":"trace[1052581447] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4203; }","duration":"234.718434ms","start":"2026-07-17T05:09:25.573108Z","end":"2026-07-17T05:09:25.807827Z","steps":["trace[1052581447] 'agreement among raft nodes before linearized reading' (duration: 234.296723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.807851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.312706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T05:09:25.807888Z","caller":"traceutil/trace.go:171","msg":"trace[1598386385] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4203; }","duration":"308.396359ms","start":"2026-07-17T05:09:25.499477Z","end":"2026-07-17T05:09:25.807873Z","steps":["trace[1598386385] 'agreement among raft nodes before linearized reading' (duration: 308.334997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.807931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:09:25.499449Z","time spent":"308.47165ms","remote":"127.0.0.1:57568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":5,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T05:09:25.80799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.288299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-17T05:09:25.808047Z","caller":"traceutil/trace.go:171","msg":"trace[1680071543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4203; }","duration":"443.379392ms","start":"2026-07-17T05:09:25.364651Z","end":"2026-07-17T05:09:25.808031Z","steps":["trace[1680071543] 'agreement among raft nodes before linearized reading' (duration: 443.200747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.80809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:09:25.36464Z","time spent":"443.441623ms","remote":"127.0.0.1:57692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T05:09:25.808157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.952558ms","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":"info","ts":"2026-07-17T05:09:25.808344Z","caller":"traceutil/trace.go:171","msg":"trace[1920794660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4203; }","duration":"199.250575ms","start":"2026-07-17T05:09:25.609075Z","end":"2026-07-17T05:09:25.808325Z","steps":["trace[1920794660] 'agreement among raft nodes before linearized reading' (duration: 198.671611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:09:25.808282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.318716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:635"} {"level":"info","ts":"2026-07-17T05:09:25.808535Z","caller":"traceutil/trace.go:171","msg":"trace[1511593026] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:4203; }","duration":"159.603793ms","start":"2026-07-17T05:09:25.648919Z","end":"2026-07-17T05:09:25.808522Z","steps":["trace[1511593026] 'agreement among raft nodes before linearized reading' (duration: 159.323396ms)"],"step_count":1}