{"level":"warn","ts":"2026-08-16T12:47:31.992424Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:47:31.992526Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-08-16T12:47:31.992635Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-16T12:47:31.992651Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-08-16T12:47:31.992671Z","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-08-16T12:47:31.993376Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-08-16T12:47:31.993458Z","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.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-08-16T12:47:31.999068Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.392989ms"} {"level":"info","ts":"2026-08-16T12:47:32.009224Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-08-16T12:47:32.009297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-08-16T12:47:32.009317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-08-16T12:47:32.009327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-16T12:47:32.009339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-08-16T12:47:32.009375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-08-16T12:47:32.015288Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-16T12:47:32.016364Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-16T12:47:32.023737Z","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-08-16T12:47:32.031119Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-16T12:47:32.03132Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-16T12:47:32.032228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-08-16T12:47:32.031464Z","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-08-16T12:47:32.032421Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-08-16T12:47:32.032429Z","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-08-16T12:47:32.032469Z","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-08-16T12:47:32.035063Z","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-08-16T12:47:32.035285Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-16T12:47:32.035335Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-16T12:47:32.035315Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-08-16T12:47:32.035561Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-08-16T12:47:32.509918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-08-16T12:47:32.510045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-16T12:47:32.510099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-08-16T12:47:32.510124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-08-16T12:47:32.510146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-08-16T12:47:32.510167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-08-16T12:47:32.510184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-08-16T12:47:32.51138Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-08-16T12:47:32.51173Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:47:32.513855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:47:32.514358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-16T12:47:32.514801Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-16T12:47:32.514844Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-16T12:47:32.514903Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:47:32.515065Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:47:32.515111Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-16T12:47:32.518517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-08-16T12:47:32.518922Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-16T12:47:35.735861Z","caller":"traceutil/trace.go:171","msg":"trace[1591916631] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"106.971062ms","start":"2026-08-16T12:47:35.628858Z","end":"2026-08-16T12:47:35.735829Z","steps":["trace[1591916631] 'process raft request' (duration: 31.441044ms)","trace[1591916631] 'compare' (duration: 75.376945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:35.942253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.172285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:35.942356Z","caller":"traceutil/trace.go:171","msg":"trace[408033479] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:233; }","duration":"106.346509ms","start":"2026-08-16T12:47:35.83599Z","end":"2026-08-16T12:47:35.942336Z","steps":["trace[408033479] 'range keys from in-memory index tree' (duration: 106.119064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:36.446349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.64625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:47:36.446577Z","caller":"traceutil/trace.go:171","msg":"trace[856232360] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"279.111817ms","start":"2026-08-16T12:47:36.167435Z","end":"2026-08-16T12:47:36.446547Z","steps":["trace[856232360] 'process raft request' (duration: 145.610148ms)","trace[856232360] 'compare' (duration: 132.507577ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:36.446663Z","caller":"traceutil/trace.go:171","msg":"trace[977127530] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"279.070367ms","start":"2026-08-16T12:47:36.167578Z","end":"2026-08-16T12:47:36.446648Z","steps":["trace[977127530] 'process raft request' (duration: 278.897233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:36.446919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.931156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:36.447007Z","caller":"traceutil/trace.go:171","msg":"trace[1889791025] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:0; response_revision:248; }","duration":"280.05929ms","start":"2026-08-16T12:47:36.166926Z","end":"2026-08-16T12:47:36.446985Z","steps":["trace[1889791025] 'agreement among raft nodes before linearized reading' (duration: 279.886736ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:36.446595Z","caller":"traceutil/trace.go:171","msg":"trace[641530536] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"210.50167ms","start":"2026-08-16T12:47:36.236079Z","end":"2026-08-16T12:47:36.446581Z","steps":["trace[641530536] 'process raft request' (duration: 210.464459ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:36.446655Z","caller":"traceutil/trace.go:171","msg":"trace[1272572406] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:250; }","duration":"249.23266ms","start":"2026-08-16T12:47:36.197357Z","end":"2026-08-16T12:47:36.44659Z","steps":["trace[1272572406] 'read index received' (duration: 115.650769ms)","trace[1272572406] 'applied index is now lower than readState.Index' (duration: 133.578771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:36.447907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.044763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-16T12:47:36.447982Z","caller":"traceutil/trace.go:171","msg":"trace[658973828] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:248; }","duration":"248.129626ms","start":"2026-08-16T12:47:36.199812Z","end":"2026-08-16T12:47:36.447942Z","steps":["trace[658973828] 'agreement among raft nodes before linearized reading' (duration: 247.993002ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:36.681697Z","caller":"traceutil/trace.go:171","msg":"trace[512856223] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:261; }","duration":"166.691173ms","start":"2026-08-16T12:47:36.514979Z","end":"2026-08-16T12:47:36.68167Z","steps":["trace[512856223] 'read index received' (duration: 73.320016ms)","trace[512856223] 'applied index is now lower than readState.Index' (duration: 93.370067ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:36.68181Z","caller":"traceutil/trace.go:171","msg":"trace[1683809894] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"168.771761ms","start":"2026-08-16T12:47:36.51301Z","end":"2026-08-16T12:47:36.681781Z","steps":["trace[1683809894] 'process raft request' (duration: 75.308372ms)","trace[1683809894] 'compare' (duration: 93.221593ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:36.681887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.883077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-16T12:47:36.681926Z","caller":"traceutil/trace.go:171","msg":"trace[973251040] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:256; }","duration":"166.954369ms","start":"2026-08-16T12:47:36.514961Z","end":"2026-08-16T12:47:36.681915Z","steps":["trace[973251040] 'agreement among raft nodes before linearized reading' (duration: 166.809005ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:36.913896Z","caller":"traceutil/trace.go:171","msg":"trace[590579094] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"180.411758ms","start":"2026-08-16T12:47:36.733451Z","end":"2026-08-16T12:47:36.913863Z","steps":["trace[590579094] 'process raft request' (duration: 112.159099ms)","trace[590579094] 'compare' (duration: 68.075505ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:56.380479Z","caller":"traceutil/trace.go:171","msg":"trace[1707845] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"313.347034ms","start":"2026-08-16T12:47:56.067115Z","end":"2026-08-16T12:47:56.380462Z","steps":["trace[1707845] 'read index received' (duration: 313.134316ms)","trace[1707845] 'applied index is now lower than readState.Index' (duration: 211.768µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:56.380575Z","caller":"traceutil/trace.go:171","msg":"trace[883514033] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"447.233323ms","start":"2026-08-16T12:47:55.933315Z","end":"2026-08-16T12:47:56.380548Z","steps":["trace[883514033] 'process raft request' (duration: 447.019235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:56.380703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.574712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:47:56.380765Z","caller":"traceutil/trace.go:171","msg":"trace[672429904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"313.677776ms","start":"2026-08-16T12:47:56.067069Z","end":"2026-08-16T12:47:56.380746Z","steps":["trace[672429904] 'agreement among raft nodes before linearized reading' (duration: 313.53271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:56.380741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:55.933296Z","time spent":"447.371109ms","remote":"127.0.0.1:34562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:47:56.380802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:56.06705Z","time spent":"313.743509ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:47:56.380792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.762488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-16T12:47:56.380878Z","caller":"traceutil/trace.go:171","msg":"trace[528920095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"122.884323ms","start":"2026-08-16T12:47:56.257973Z","end":"2026-08-16T12:47:56.380857Z","steps":["trace[528920095] 'agreement among raft nodes before linearized reading' (duration: 122.742868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:56.380901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.516732ms","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-08-16T12:47:56.380989Z","caller":"traceutil/trace.go:171","msg":"trace[607653731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"280.619896ms","start":"2026-08-16T12:47:56.100357Z","end":"2026-08-16T12:47:56.380977Z","steps":["trace[607653731] 'agreement among raft nodes before linearized reading' (duration: 280.370406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:56.380993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.603653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-16T12:47:56.38108Z","caller":"traceutil/trace.go:171","msg":"trace[678586791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:434; }","duration":"282.763188ms","start":"2026-08-16T12:47:56.098269Z","end":"2026-08-16T12:47:56.381032Z","steps":["trace[678586791] 'agreement among raft nodes before linearized reading' (duration: 282.55311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:56.381188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.676692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-16T12:47:56.38127Z","caller":"traceutil/trace.go:171","msg":"trace[563775552] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:434; }","duration":"310.784436ms","start":"2026-08-16T12:47:56.070478Z","end":"2026-08-16T12:47:56.381262Z","steps":["trace[563775552] 'agreement among raft nodes before linearized reading' (duration: 310.225194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:56.381363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:47:56.070466Z","time spent":"310.887179ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"info","ts":"2026-08-16T12:47:56.616765Z","caller":"traceutil/trace.go:171","msg":"trace[1499544290] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"164.43175ms","start":"2026-08-16T12:47:56.452312Z","end":"2026-08-16T12:47:56.616743Z","steps":["trace[1499544290] 'read index received' (duration: 104.58963ms)","trace[1499544290] 'applied index is now lower than readState.Index' (duration: 59.84121ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:47:56.616933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.597106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-08-16T12:47:56.61692Z","caller":"traceutil/trace.go:171","msg":"trace[165457064] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"165.22427ms","start":"2026-08-16T12:47:56.451654Z","end":"2026-08-16T12:47:56.616878Z","steps":["trace[165457064] 'process raft request' (duration: 105.340008ms)","trace[165457064] 'compare' (duration: 59.654433ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:56.61697Z","caller":"traceutil/trace.go:171","msg":"trace[1263395457] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:464; }","duration":"164.667779ms","start":"2026-08-16T12:47:56.452291Z","end":"2026-08-16T12:47:56.616959Z","steps":["trace[1263395457] 'agreement among raft nodes before linearized reading' (duration: 164.580906ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:58.517701Z","caller":"traceutil/trace.go:171","msg":"trace[1833734466] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"122.59967ms","start":"2026-08-16T12:47:58.395074Z","end":"2026-08-16T12:47:58.517674Z","steps":["trace[1833734466] 'read index received' (duration: 122.531487ms)","trace[1833734466] 'applied index is now lower than readState.Index' (duration: 67.093µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:47:58.517735Z","caller":"traceutil/trace.go:171","msg":"trace[652542749] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"124.333818ms","start":"2026-08-16T12:47:58.393373Z","end":"2026-08-16T12:47:58.517707Z","steps":["trace[652542749] 'process raft request' (duration: 124.160091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:58.517827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.728455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-16T12:47:58.517943Z","caller":"traceutil/trace.go:171","msg":"trace[1275651933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"122.87175ms","start":"2026-08-16T12:47:58.395058Z","end":"2026-08-16T12:47:58.51793Z","steps":["trace[1275651933] 'agreement among raft nodes before linearized reading' (duration: 122.695483ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:47:58.52281Z","caller":"traceutil/trace.go:171","msg":"trace[372452119] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"126.657811ms","start":"2026-08-16T12:47:58.396134Z","end":"2026-08-16T12:47:58.522792Z","steps":["trace[372452119] 'process raft request' (duration: 126.504905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:47:58.523367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.63102ms","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-08-16T12:47:58.523509Z","caller":"traceutil/trace.go:171","msg":"trace[742664166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"126.708753ms","start":"2026-08-16T12:47:58.396695Z","end":"2026-08-16T12:47:58.523404Z","steps":["trace[742664166] 'agreement among raft nodes before linearized reading' (duration: 126.614049ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:00.651927Z","caller":"traceutil/trace.go:171","msg":"trace[1211576949] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"119.014957ms","start":"2026-08-16T12:48:00.532901Z","end":"2026-08-16T12:48:00.651916Z","steps":["trace[1211576949] 'read index received' (duration: 118.898983ms)","trace[1211576949] 'applied index is now lower than readState.Index' (duration: 115.324µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:00.652025Z","caller":"traceutil/trace.go:171","msg":"trace[1967858871] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"120.565361ms","start":"2026-08-16T12:48:00.531438Z","end":"2026-08-16T12:48:00.652003Z","steps":["trace[1967858871] 'process raft request' (duration: 120.405524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:00.652157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.216576ms","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-08-16T12:48:00.652264Z","caller":"traceutil/trace.go:171","msg":"trace[41237227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"119.351951ms","start":"2026-08-16T12:48:00.532878Z","end":"2026-08-16T12:48:00.65223Z","steps":["trace[41237227] 'agreement among raft nodes before linearized reading' (duration: 119.147213ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:00.653612Z","caller":"traceutil/trace.go:171","msg":"trace[120193081] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"119.036628ms","start":"2026-08-16T12:48:00.53455Z","end":"2026-08-16T12:48:00.653587Z","steps":["trace[120193081] 'process raft request' (duration: 118.706504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:01.836373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.740638ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-16T12:48:02.897305Z","caller":"traceutil/trace.go:171","msg":"trace[664921243] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"236.855674ms","start":"2026-08-16T12:48:02.66043Z","end":"2026-08-16T12:48:02.897285Z","steps":["trace[664921243] 'read index received' (duration: 236.691067ms)","trace[664921243] 'applied index is now lower than readState.Index' (duration: 163.817µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:02.897459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.996881ms","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-08-16T12:48:02.897491Z","caller":"traceutil/trace.go:171","msg":"trace[2001807468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:478; }","duration":"237.074913ms","start":"2026-08-16T12:48:02.660407Z","end":"2026-08-16T12:48:02.897482Z","steps":["trace[2001807468] 'agreement among raft nodes before linearized reading' (duration: 236.973899ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:02.897464Z","caller":"traceutil/trace.go:171","msg":"trace[1166767860] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"238.023434ms","start":"2026-08-16T12:48:02.659408Z","end":"2026-08-16T12:48:02.897431Z","steps":["trace[1166767860] 'process raft request' (duration: 237.754343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:03.998932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.25838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:48:03.999024Z","caller":"traceutil/trace.go:171","msg":"trace[540867697] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"1.101649136s","start":"2026-08-16T12:48:02.897359Z","end":"2026-08-16T12:48:03.999008Z","steps":["trace[540867697] 'read index received' (duration: 499.24441ms)","trace[540867697] 'applied index is now lower than readState.Index' (duration: 602.403616ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:03.999088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337826742s","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-08-16T12:48:03.999115Z","caller":"traceutil/trace.go:171","msg":"trace[1620037129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"1.337870463s","start":"2026-08-16T12:48:02.661235Z","end":"2026-08-16T12:48:03.999105Z","steps":["trace[1620037129] 'agreement among raft nodes before linearized reading' (duration: 1.337815071s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:03.999149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:02.661224Z","time spent":"1.337915255s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-16T12:48:03.999291Z","caller":"traceutil/trace.go:171","msg":"trace[598239129] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.338449558s","start":"2026-08-16T12:48:02.660799Z","end":"2026-08-16T12:48:03.999248Z","steps":["trace[598239129] 'process raft request' (duration: 735.792861ms)","trace[598239129] 'compare' (duration: 602.103533ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:03.999417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:02.66077Z","time spent":"1.338574643s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:48:04.567394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540608042347807,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:48:04.588182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.191569557s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:48:04.638913Z","caller":"traceutil/trace.go:171","msg":"trace[59480633] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.738225278s","start":"2026-08-16T12:48:02.900654Z","end":"2026-08-16T12:48:04.63888Z","steps":["trace[59480633] 'process raft request' (duration: 1.687671515s)","trace[59480633] 'compare' (duration: 50.443758ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:04.639073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:02.900638Z","time spent":"1.738362584s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:48:04.660045Z","caller":"traceutil/trace.go:171","msg":"trace[1707446602] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:495; }","duration":"593.381706ms","start":"2026-08-16T12:48:04.066647Z","end":"2026-08-16T12:48:04.660029Z","steps":["trace[1707446602] 'read index received' (duration: 521.685226ms)","trace[1707446602] 'applied index is now lower than readState.Index' (duration: 71.69586ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:04.660084Z","caller":"traceutil/trace.go:171","msg":"trace[826243045] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"657.772289ms","start":"2026-08-16T12:48:04.00228Z","end":"2026-08-16T12:48:04.660052Z","steps":["trace[826243045] 'process raft request' (duration: 657.645544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:04.660126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.47096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:04.660148Z","caller":"traceutil/trace.go:171","msg":"trace[125574478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"593.540313ms","start":"2026-08-16T12:48:04.066601Z","end":"2026-08-16T12:48:04.660141Z","steps":["trace[125574478] 'agreement among raft nodes before linearized reading' (duration: 593.488911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:04.660171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:04.066588Z","time spent":"593.578735ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:48:04.660181Z","caller":"traceutil/trace.go:171","msg":"trace[1214482185] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"266.674356ms","start":"2026-08-16T12:48:04.393496Z","end":"2026-08-16T12:48:04.66017Z","steps":["trace[1214482185] 'process raft request' (duration: 266.508569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:04.660231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:04.002264Z","time spent":"657.881894ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:48:06.86841Z","caller":"traceutil/trace.go:171","msg":"trace[590684132] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"203.09015ms","start":"2026-08-16T12:48:06.665297Z","end":"2026-08-16T12:48:06.868388Z","steps":["trace[590684132] 'read index received' (duration: 202.907812ms)","trace[590684132] 'applied index is now lower than readState.Index' (duration: 181.418µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:06.868575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.258807ms","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-08-16T12:48:06.868643Z","caller":"traceutil/trace.go:171","msg":"trace[2034627753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"203.370683ms","start":"2026-08-16T12:48:06.665259Z","end":"2026-08-16T12:48:06.86863Z","steps":["trace[2034627753] 'agreement among raft nodes before linearized reading' (duration: 203.241377ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:06.86906Z","caller":"traceutil/trace.go:171","msg":"trace[1030757874] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"222.274424ms","start":"2026-08-16T12:48:06.646191Z","end":"2026-08-16T12:48:06.868465Z","steps":["trace[1030757874] 'process raft request' (duration: 222.066924ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:09.807034Z","caller":"traceutil/trace.go:171","msg":"trace[1850790481] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"178.625605ms","start":"2026-08-16T12:48:09.628378Z","end":"2026-08-16T12:48:09.807003Z","steps":["trace[1850790481] 'process raft request' (duration: 173.375062ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:09.807531Z","caller":"traceutil/trace.go:171","msg":"trace[897541879] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"175.384325ms","start":"2026-08-16T12:48:09.632097Z","end":"2026-08-16T12:48:09.807481Z","steps":["trace[897541879] 'process raft request' (duration: 175.009357ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:09.807809Z","caller":"traceutil/trace.go:171","msg":"trace[144048342] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"127.473114ms","start":"2026-08-16T12:48:09.68032Z","end":"2026-08-16T12:48:09.807793Z","steps":["trace[144048342] 'process raft request' (duration: 127.103197ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:09.807789Z","caller":"traceutil/trace.go:171","msg":"trace[1372570029] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"109.656991ms","start":"2026-08-16T12:48:09.698117Z","end":"2026-08-16T12:48:09.807774Z","steps":["trace[1372570029] 'process raft request' (duration: 109.523305ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:16.837831Z","caller":"traceutil/trace.go:171","msg":"trace[1514668220] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"249.903341ms","start":"2026-08-16T12:48:16.587901Z","end":"2026-08-16T12:48:16.837804Z","steps":["trace[1514668220] 'process raft request' (duration: 241.297604ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:19.379334Z","caller":"traceutil/trace.go:171","msg":"trace[1236648605] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"509.721668ms","start":"2026-08-16T12:48:18.869588Z","end":"2026-08-16T12:48:19.37931Z","steps":["trace[1236648605] 'process raft request' (duration: 506.756756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:19.379478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:18.869565Z","time spent":"509.855864ms","remote":"127.0.0.1:34384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:48:19.382394Z","caller":"traceutil/trace.go:171","msg":"trace[1264461589] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"423.580947ms","start":"2026-08-16T12:48:18.958797Z","end":"2026-08-16T12:48:19.382378Z","steps":["trace[1264461589] 'read index received' (duration: 417.560739ms)","trace[1264461589] 'applied index is now lower than readState.Index' (duration: 6.019228ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:19.382587Z","caller":"traceutil/trace.go:171","msg":"trace[452004836] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"483.113982ms","start":"2026-08-16T12:48:18.899464Z","end":"2026-08-16T12:48:19.382578Z","steps":["trace[452004836] 'process raft request' (duration: 482.840818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:19.382652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:18.899438Z","time spent":"483.167874ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:48:19.38269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.880492ms","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-08-16T12:48:19.382779Z","caller":"traceutil/trace.go:171","msg":"trace[509562250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"424.03678ms","start":"2026-08-16T12:48:18.958727Z","end":"2026-08-16T12:48:19.382764Z","steps":["trace[509562250] 'agreement among raft nodes before linearized reading' (duration: 423.883533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:19.382818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:18.958712Z","time spent":"424.099784ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:48:19.383055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.366999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:19.383112Z","caller":"traceutil/trace.go:171","msg":"trace[530729841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"316.468124ms","start":"2026-08-16T12:48:19.066635Z","end":"2026-08-16T12:48:19.383104Z","steps":["trace[530729841] 'agreement among raft nodes before linearized reading' (duration: 316.39631ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:19.383148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:19.066609Z","time spent":"316.533698ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:48:19.383196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.454927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-16T12:48:19.383375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.915677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-16T12:48:19.383406Z","caller":"traceutil/trace.go:171","msg":"trace[2083290122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"343.9706ms","start":"2026-08-16T12:48:19.039429Z","end":"2026-08-16T12:48:19.3834Z","steps":["trace[2083290122] 'agreement among raft nodes before linearized reading' (duration: 343.873175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:19.383428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:19.039413Z","time spent":"344.010162ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-16T12:48:19.383436Z","caller":"traceutil/trace.go:171","msg":"trace[625757328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"418.802586ms","start":"2026-08-16T12:48:18.964574Z","end":"2026-08-16T12:48:19.383376Z","steps":["trace[625757328] 'agreement among raft nodes before linearized reading' (duration: 418.314911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:19.383558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:18.964561Z","time spent":"418.977724ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-16T12:48:21.574801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710540608042348193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-16T12:48:21.591124Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.509565367s","expected-duration":"1s"} {"level":"info","ts":"2026-08-16T12:48:21.591495Z","caller":"traceutil/trace.go:171","msg":"trace[150653545] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"1.510006931s","start":"2026-08-16T12:48:20.081464Z","end":"2026-08-16T12:48:21.591471Z","steps":["trace[150653545] 'process raft request' (duration: 1.509861653s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:21.591642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:20.081448Z","time spent":"1.510117987s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:48:21.598194Z","caller":"traceutil/trace.go:171","msg":"trace[1431637305] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"530.638618ms","start":"2026-08-16T12:48:21.067524Z","end":"2026-08-16T12:48:21.598163Z","steps":["trace[1431637305] 'read index received' (duration: 523.847395ms)","trace[1431637305] 'applied index is now lower than readState.Index' (duration: 6.789573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:21.598325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.794716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:21.598374Z","caller":"traceutil/trace.go:171","msg":"trace[157145000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"530.904001ms","start":"2026-08-16T12:48:21.067453Z","end":"2026-08-16T12:48:21.598357Z","steps":["trace[157145000] 'agreement among raft nodes before linearized reading' (duration: 530.812236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:21.598409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:21.067424Z","time spent":"530.979396ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-16T12:48:21.59848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.78713ms","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-08-16T12:48:21.598556Z","caller":"traceutil/trace.go:171","msg":"trace[531419551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"199.899976ms","start":"2026-08-16T12:48:21.398641Z","end":"2026-08-16T12:48:21.598541Z","steps":["trace[531419551] 'agreement among raft nodes before linearized reading' (duration: 199.651643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:21.598799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.571497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-16T12:48:21.598842Z","caller":"traceutil/trace.go:171","msg":"trace[545680357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"198.63036ms","start":"2026-08-16T12:48:21.400202Z","end":"2026-08-16T12:48:21.598832Z","steps":["trace[545680357] 'agreement among raft nodes before linearized reading' (duration: 198.429009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:21.59895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.228001ms","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-08-16T12:48:21.598989Z","caller":"traceutil/trace.go:171","msg":"trace[1483647988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"199.341137ms","start":"2026-08-16T12:48:21.39964Z","end":"2026-08-16T12:48:21.598981Z","steps":["trace[1483647988] 'agreement among raft nodes before linearized reading' (duration: 199.230651ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:26.052997Z","caller":"traceutil/trace.go:171","msg":"trace[818974408] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"373.242272ms","start":"2026-08-16T12:48:25.679721Z","end":"2026-08-16T12:48:26.052963Z","steps":["trace[818974408] 'process raft request' (duration: 356.894057ms)","trace[818974408] 'compare' (duration: 16.040419ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:26.053129Z","caller":"traceutil/trace.go:171","msg":"trace[1482950489] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"372.26233ms","start":"2026-08-16T12:48:25.68085Z","end":"2026-08-16T12:48:26.053112Z","steps":["trace[1482950489] 'process raft request' (duration: 372.037348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:26.053226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:25.679709Z","time spent":"373.37817ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:48:26.053237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:25.68084Z","time spent":"372.352695ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:48:26.80983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.851108ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-08-16T12:48:26.809934Z","caller":"traceutil/trace.go:171","msg":"trace[284881599] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"719.680004ms","start":"2026-08-16T12:48:26.09024Z","end":"2026-08-16T12:48:26.80992Z","steps":["trace[284881599] 'read index received' (duration: 220.72048ms)","trace[284881599] 'applied index is now lower than readState.Index' (duration: 498.958023ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:26.810451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.843092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gsd6n\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-16T12:48:26.810495Z","caller":"traceutil/trace.go:171","msg":"trace[225984087] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gsd6n; range_end:; response_count:1; response_revision:665; }","duration":"720.288156ms","start":"2026-08-16T12:48:26.090198Z","end":"2026-08-16T12:48:26.810486Z","steps":["trace[225984087] 'agreement among raft nodes before linearized reading' (duration: 719.756687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:26.810521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:26.090182Z","time spent":"720.332539ms","remote":"127.0.0.1:34344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-gsd6n\" "} {"level":"warn","ts":"2026-08-16T12:48:26.810784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:26.074143Z","time spent":"736.636191ms","remote":"127.0.0.1:34212","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-16T12:48:27.077385Z","caller":"traceutil/trace.go:171","msg":"trace[731147934] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"236.978488ms","start":"2026-08-16T12:48:26.840377Z","end":"2026-08-16T12:48:27.077356Z","steps":["trace[731147934] 'read index received' (duration: 191.236884ms)","trace[731147934] 'applied index is now lower than readState.Index' (duration: 45.740764ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:48:27.077469Z","caller":"traceutil/trace.go:171","msg":"trace[1208972136] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"252.315739ms","start":"2026-08-16T12:48:26.825123Z","end":"2026-08-16T12:48:27.077439Z","steps":["trace[1208972136] 'process raft request' (duration: 206.50744ms)","trace[1208972136] 'compare' (duration: 45.619598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:27.077512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.119095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-08-16T12:48:27.077559Z","caller":"traceutil/trace.go:171","msg":"trace[498543004] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:667; }","duration":"237.205139ms","start":"2026-08-16T12:48:26.840343Z","end":"2026-08-16T12:48:27.077548Z","steps":["trace[498543004] 'agreement among raft nodes before linearized reading' (duration: 237.101974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:28.878311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154204076s","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-08-16T12:48:28.878393Z","caller":"traceutil/trace.go:171","msg":"trace[1163067851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"1.154327122s","start":"2026-08-16T12:48:27.724049Z","end":"2026-08-16T12:48:28.878376Z","steps":["trace[1163067851] 'range keys from in-memory index tree' (duration: 1.154040756s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:28.878438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:27.724035Z","time spent":"1.154394486s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-16T12:48:28.878903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198816289s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-16T12:48:28.878968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.205991ms","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-08-16T12:48:28.879005Z","caller":"traceutil/trace.go:171","msg":"trace[993812041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"1.198972497s","start":"2026-08-16T12:48:27.680005Z","end":"2026-08-16T12:48:28.878978Z","steps":["trace[993812041] 'range keys from in-memory index tree' (duration: 1.198597937s)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:28.879072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:27.679981Z","time spent":"1.199080754s","remote":"127.0.0.1:34414","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":"info","ts":"2026-08-16T12:48:28.879068Z","caller":"traceutil/trace.go:171","msg":"trace[507883751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"821.329398ms","start":"2026-08-16T12:48:28.057702Z","end":"2026-08-16T12:48:28.879031Z","steps":["trace[507883751] 'range keys from in-memory index tree' (duration: 821.030902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:28.879123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:28.05768Z","time spent":"821.433794ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-16T12:48:28.880194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.457791ms","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-08-16T12:48:28.880323Z","caller":"traceutil/trace.go:171","msg":"trace[646227249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"822.633871ms","start":"2026-08-16T12:48:28.05766Z","end":"2026-08-16T12:48:28.880294Z","steps":["trace[646227249] 'range keys from in-memory index tree' (duration: 821.119687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:28.880384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:28.05764Z","time spent":"822.733406ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-16T12:48:28.989084Z","caller":"traceutil/trace.go:171","msg":"trace[1915776196] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"104.547445ms","start":"2026-08-16T12:48:28.884515Z","end":"2026-08-16T12:48:28.989063Z","steps":["trace[1915776196] 'process raft request' (duration: 104.381506ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:29.050395Z","caller":"traceutil/trace.go:171","msg":"trace[221306637] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"165.828509ms","start":"2026-08-16T12:48:28.884539Z","end":"2026-08-16T12:48:29.050368Z","steps":["trace[221306637] 'process raft request' (duration: 165.49472ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:29.050463Z","caller":"traceutil/trace.go:171","msg":"trace[1018923624] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"165.534543ms","start":"2026-08-16T12:48:28.884858Z","end":"2026-08-16T12:48:29.050393Z","steps":["trace[1018923624] 'process raft request' (duration: 165.4791ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:29.05061Z","caller":"traceutil/trace.go:171","msg":"trace[2008300583] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"165.936665ms","start":"2026-08-16T12:48:28.884614Z","end":"2026-08-16T12:48:29.050551Z","steps":["trace[2008300583] 'process raft request' (duration: 165.649689ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:31.373884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.114022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:31.374643Z","caller":"traceutil/trace.go:171","msg":"trace[2054565770] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:692; }","duration":"104.915486ms","start":"2026-08-16T12:48:31.269684Z","end":"2026-08-16T12:48:31.374599Z","steps":["trace[2054565770] 'agreement among raft nodes before linearized reading' (duration: 104.08568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:31.374291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.224135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:31.374963Z","caller":"traceutil/trace.go:171","msg":"trace[1812500361] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:692; }","duration":"102.939696ms","start":"2026-08-16T12:48:31.27201Z","end":"2026-08-16T12:48:31.37495Z","steps":["trace[1812500361] 'agreement among raft nodes before linearized reading' (duration: 102.220516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:31.374332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.729132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:31.375071Z","caller":"traceutil/trace.go:171","msg":"trace[46863129] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:692; }","duration":"101.515225ms","start":"2026-08-16T12:48:31.273531Z","end":"2026-08-16T12:48:31.375046Z","steps":["trace[46863129] 'agreement among raft nodes before linearized reading' (duration: 100.758573ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:31.374419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.84041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:31.375167Z","caller":"traceutil/trace.go:171","msg":"trace[985637304] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:692; }","duration":"103.569882ms","start":"2026-08-16T12:48:31.271567Z","end":"2026-08-16T12:48:31.375136Z","steps":["trace[985637304] 'agreement among raft nodes before linearized reading' (duration: 102.820439ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:33.41811Z","caller":"traceutil/trace.go:171","msg":"trace[1184561344] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"108.912853ms","start":"2026-08-16T12:48:33.309164Z","end":"2026-08-16T12:48:33.418077Z","steps":["trace[1184561344] 'process raft request' (duration: 108.558002ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:41.289652Z","caller":"traceutil/trace.go:171","msg":"trace[593061182] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"168.318723ms","start":"2026-08-16T12:48:41.121288Z","end":"2026-08-16T12:48:41.289607Z","steps":["trace[593061182] 'process raft request' (duration: 166.733928ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:41.3008Z","caller":"traceutil/trace.go:171","msg":"trace[1705650414] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"178.682641ms","start":"2026-08-16T12:48:41.122096Z","end":"2026-08-16T12:48:41.300779Z","steps":["trace[1705650414] 'process raft request' (duration: 178.568174ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:48:41.301038Z","caller":"traceutil/trace.go:171","msg":"trace[257219336] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"176.206334ms","start":"2026-08-16T12:48:41.12481Z","end":"2026-08-16T12:48:41.301016Z","steps":["trace[257219336] 'process raft request' (duration: 175.943218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:44.576649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.037486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:44.577053Z","caller":"traceutil/trace.go:171","msg":"trace[1284637649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"509.502874ms","start":"2026-08-16T12:48:44.067532Z","end":"2026-08-16T12:48:44.577034Z","steps":["trace[1284637649] 'range keys from in-memory index tree' (duration: 508.946601ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:44.5771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:44.067512Z","time spent":"509.575828ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-16T12:48:44.683207Z","caller":"traceutil/trace.go:171","msg":"trace[1836071564] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"439.101331ms","start":"2026-08-16T12:48:44.244087Z","end":"2026-08-16T12:48:44.683188Z","steps":["trace[1836071564] 'process raft request' (duration: 438.92043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:48:44.683331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:48:44.244067Z","time spent":"439.194637ms","remote":"127.0.0.1:34384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-16T12:48:44.68334Z","caller":"traceutil/trace.go:171","msg":"trace[1455964103] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"104.196209ms","start":"2026-08-16T12:48:44.579109Z","end":"2026-08-16T12:48:44.683305Z","steps":["trace[1455964103] 'read index received' (duration: 103.960075ms)","trace[1455964103] 'applied index is now lower than readState.Index' (duration: 234.394µs)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:48:44.683424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.307187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:48:44.68346Z","caller":"traceutil/trace.go:171","msg":"trace[1070571966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:826; }","duration":"104.381101ms","start":"2026-08-16T12:48:44.57907Z","end":"2026-08-16T12:48:44.683451Z","steps":["trace[1070571966] 'agreement among raft nodes before linearized reading' (duration: 104.318507ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:17.618311Z","caller":"traceutil/trace.go:171","msg":"trace[2003526363] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1102; }","duration":"160.368644ms","start":"2026-08-16T12:49:17.45792Z","end":"2026-08-16T12:49:17.618288Z","steps":["trace[2003526363] 'read index received' (duration: 93.424039ms)","trace[2003526363] 'applied index is now lower than readState.Index' (duration: 66.943645ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:49:17.618344Z","caller":"traceutil/trace.go:171","msg":"trace[1393553212] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"160.796644ms","start":"2026-08-16T12:49:17.457522Z","end":"2026-08-16T12:49:17.618319Z","steps":["trace[1393553212] 'process raft request' (duration: 160.711778ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:49:17.618353Z","caller":"traceutil/trace.go:171","msg":"trace[1736126010] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"163.054929ms","start":"2026-08-16T12:49:17.455267Z","end":"2026-08-16T12:49:17.618322Z","steps":["trace[1736126010] 'process raft request' (duration: 96.06801ms)","trace[1736126010] 'compare' (duration: 66.742452ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:49:17.618495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.556548ms","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-08-16T12:49:17.618528Z","caller":"traceutil/trace.go:171","msg":"trace[1273951187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1069; }","duration":"160.632523ms","start":"2026-08-16T12:49:17.457885Z","end":"2026-08-16T12:49:17.618518Z","steps":["trace[1273951187] 'agreement among raft nodes before linearized reading' (duration: 160.497864ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:01.03828Z","caller":"traceutil/trace.go:171","msg":"trace[329819451] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"194.519902ms","start":"2026-08-16T12:50:00.843734Z","end":"2026-08-16T12:50:01.038254Z","steps":["trace[329819451] 'process raft request' (duration: 194.384822ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:02.136783Z","caller":"traceutil/trace.go:171","msg":"trace[1110936617] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"223.933164ms","start":"2026-08-16T12:50:01.912819Z","end":"2026-08-16T12:50:02.136752Z","steps":["trace[1110936617] 'read index received' (duration: 223.763072ms)","trace[1110936617] 'applied index is now lower than readState.Index' (duration: 168.812µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:50:02.136914Z","caller":"traceutil/trace.go:171","msg":"trace[158332862] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"224.779187ms","start":"2026-08-16T12:50:01.912102Z","end":"2026-08-16T12:50:02.136881Z","steps":["trace[158332862] 'process raft request' (duration: 224.500286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:02.137064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.221946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-16T12:50:02.137088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.565683ms","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-08-16T12:50:02.137136Z","caller":"traceutil/trace.go:171","msg":"trace[1626053028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"157.66343ms","start":"2026-08-16T12:50:01.979461Z","end":"2026-08-16T12:50:02.137125Z","steps":["trace[1626053028] 'agreement among raft nodes before linearized reading' (duration: 157.514029ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:02.137123Z","caller":"traceutil/trace.go:171","msg":"trace[1317105038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"224.28766ms","start":"2026-08-16T12:50:01.912815Z","end":"2026-08-16T12:50:02.137103Z","steps":["trace[1317105038] 'agreement among raft nodes before linearized reading' (duration: 224.107947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:02.137243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.965943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-16T12:50:02.137257Z","caller":"traceutil/trace.go:171","msg":"trace[1961725959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"156.001466ms","start":"2026-08-16T12:50:01.981251Z","end":"2026-08-16T12:50:02.137253Z","steps":["trace[1961725959] 'agreement among raft nodes before linearized reading' (duration: 155.960082ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:04.333025Z","caller":"traceutil/trace.go:171","msg":"trace[1250388490] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"188.214398ms","start":"2026-08-16T12:50:04.144786Z","end":"2026-08-16T12:50:04.333001Z","steps":["trace[1250388490] 'read index received' (duration: 187.97394ms)","trace[1250388490] 'applied index is now lower than readState.Index' (duration: 239.458µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:50:04.333077Z","caller":"traceutil/trace.go:171","msg":"trace[1461041033] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"189.356743ms","start":"2026-08-16T12:50:04.143699Z","end":"2026-08-16T12:50:04.333056Z","steps":["trace[1461041033] 'process raft request' (duration: 189.156868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:04.33332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.514751ms","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-08-16T12:50:04.333365Z","caller":"traceutil/trace.go:171","msg":"trace[1717683529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"188.599547ms","start":"2026-08-16T12:50:04.144754Z","end":"2026-08-16T12:50:04.333353Z","steps":["trace[1717683529] 'agreement among raft nodes before linearized reading' (duration: 188.416023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:04.333388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.913622ms","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-08-16T12:50:04.333471Z","caller":"traceutil/trace.go:171","msg":"trace[726607376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1223; }","duration":"187.02267ms","start":"2026-08-16T12:50:04.146422Z","end":"2026-08-16T12:50:04.333445Z","steps":["trace[726607376] 'agreement among raft nodes before linearized reading' (duration: 186.832486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:04.333585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.559945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-16T12:50:04.333923Z","caller":"traceutil/trace.go:171","msg":"trace[346130839] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1223; }","duration":"186.945924ms","start":"2026-08-16T12:50:04.146958Z","end":"2026-08-16T12:50:04.333904Z","steps":["trace[346130839] 'agreement among raft nodes before linearized reading' (duration: 186.454657ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:17.923167Z","caller":"traceutil/trace.go:171","msg":"trace[525830409] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"185.321423ms","start":"2026-08-16T12:50:17.737821Z","end":"2026-08-16T12:50:17.923142Z","steps":["trace[525830409] 'process raft request' (duration: 185.184752ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:21.101731Z","caller":"traceutil/trace.go:171","msg":"trace[1073348223] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"115.576ms","start":"2026-08-16T12:50:20.98613Z","end":"2026-08-16T12:50:21.101706Z","steps":["trace[1073348223] 'process raft request' (duration: 115.374355ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:22.49518Z","caller":"traceutil/trace.go:171","msg":"trace[896367015] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"100.536417ms","start":"2026-08-16T12:50:22.394617Z","end":"2026-08-16T12:50:22.495154Z","steps":["trace[896367015] 'process raft request' (duration: 100.371264ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:22.621435Z","caller":"traceutil/trace.go:171","msg":"trace[693270879] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1342; }","duration":"113.450247ms","start":"2026-08-16T12:50:22.507962Z","end":"2026-08-16T12:50:22.621412Z","steps":["trace[693270879] 'read index received' (duration: 41.802015ms)","trace[693270879] 'applied index is now lower than readState.Index' (duration: 71.647392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:50:22.621628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.650643ms","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-08-16T12:50:22.621651Z","caller":"traceutil/trace.go:171","msg":"trace[1855352884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1294; }","duration":"113.714448ms","start":"2026-08-16T12:50:22.507931Z","end":"2026-08-16T12:50:22.621645Z","steps":["trace[1855352884] 'agreement among raft nodes before linearized reading' (duration: 113.583978ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:22.621511Z","caller":"traceutil/trace.go:171","msg":"trace[2010902923] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"116.180584ms","start":"2026-08-16T12:50:22.505302Z","end":"2026-08-16T12:50:22.621482Z","steps":["trace[2010902923] 'process raft request' (duration: 108.74704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:24.450699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.0688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-16T12:50:24.450921Z","caller":"traceutil/trace.go:171","msg":"trace[758662543] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"321.812706ms","start":"2026-08-16T12:50:24.129068Z","end":"2026-08-16T12:50:24.45088Z","steps":["trace[758662543] 'process raft request' (duration: 36.442013ms)","trace[758662543] 'compare' (duration: 284.495406ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:50:24.45102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:24.129047Z","time spent":"321.943025ms","remote":"127.0.0.1:34250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":853,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-16T12:50:27.135737Z","caller":"traceutil/trace.go:171","msg":"trace[1031739807] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"100.282299ms","start":"2026-08-16T12:50:27.035431Z","end":"2026-08-16T12:50:27.135713Z","steps":["trace[1031739807] 'process raft request' (duration: 100.010349ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:28.158498Z","caller":"traceutil/trace.go:171","msg":"trace[1399009937] linearizableReadLoop","detail":"{readStateIndex:1380; appliedIndex:1379; }","duration":"265.76117ms","start":"2026-08-16T12:50:27.892717Z","end":"2026-08-16T12:50:28.158478Z","steps":["trace[1399009937] 'read index received' (duration: 217.620865ms)","trace[1399009937] 'applied index is now lower than readState.Index' (duration: 48.139215ms)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:50:28.158597Z","caller":"traceutil/trace.go:171","msg":"trace[306257313] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"316.641571ms","start":"2026-08-16T12:50:27.841943Z","end":"2026-08-16T12:50:28.158585Z","steps":["trace[306257313] 'process raft request' (duration: 268.335084ms)","trace[306257313] 'compare' (duration: 47.806319ms)"],"step_count":2} {"level":"warn","ts":"2026-08-16T12:50:28.158694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:27.841924Z","time spent":"316.697486ms","remote":"127.0.0.1:34344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2460,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:50:28.159151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.42028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-08-16T12:50:28.159227Z","caller":"traceutil/trace.go:171","msg":"trace[1634051257] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1329; }","duration":"266.53758ms","start":"2026-08-16T12:50:27.892672Z","end":"2026-08-16T12:50:28.159209Z","steps":["trace[1634051257] 'agreement among raft nodes before linearized reading' (duration: 266.295251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:28.160508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.698293ms","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-08-16T12:50:28.160588Z","caller":"traceutil/trace.go:171","msg":"trace[1778442425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"204.780469ms","start":"2026-08-16T12:50:27.955794Z","end":"2026-08-16T12:50:28.160575Z","steps":["trace[1778442425] 'agreement among raft nodes before linearized reading' (duration: 204.542301ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:28.160638Z","caller":"traceutil/trace.go:171","msg":"trace[1565659898] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"129.940919ms","start":"2026-08-16T12:50:28.030647Z","end":"2026-08-16T12:50:28.160588Z","steps":["trace[1565659898] 'process raft request' (duration: 129.546729ms)"],"step_count":1} {"level":"info","ts":"2026-08-16T12:50:38.515381Z","caller":"traceutil/trace.go:171","msg":"trace[2118143318] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"316.088764ms","start":"2026-08-16T12:50:38.199261Z","end":"2026-08-16T12:50:38.51535Z","steps":["trace[2118143318] 'read index received' (duration: 315.797772ms)","trace[2118143318] 'applied index is now lower than readState.Index' (duration: 289.762µs)"],"step_count":2} {"level":"info","ts":"2026-08-16T12:50:38.515483Z","caller":"traceutil/trace.go:171","msg":"trace[998659091] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"317.809226ms","start":"2026-08-16T12:50:38.197644Z","end":"2026-08-16T12:50:38.515454Z","steps":["trace[998659091] 'process raft request' (duration: 317.535115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:38.515568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.28738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-16T12:50:38.515621Z","caller":"traceutil/trace.go:171","msg":"trace[1614789277] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1372; }","duration":"316.372205ms","start":"2026-08-16T12:50:38.199231Z","end":"2026-08-16T12:50:38.515603Z","steps":["trace[1614789277] 'agreement among raft nodes before linearized reading' (duration: 316.283489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-16T12:50:38.515628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:38.197625Z","time spent":"317.926135ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-16T12:50:38.515667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-16T12:50:38.199221Z","time spent":"316.43472ms","remote":"127.0.0.1:34300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-08-16T12:50:38.515779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.304011ms","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-08-16T12:50:38.515832Z","caller":"traceutil/trace.go:171","msg":"trace[1723584286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1372; }","duration":"226.392447ms","start":"2026-08-16T12:50:38.289426Z","end":"2026-08-16T12:50:38.515818Z","steps":["trace[1723584286] 'agreement among raft nodes before linearized reading' (duration: 226.228024ms)"],"step_count":1}