{"level":"warn","ts":"2026-07-14T16:56:32.923004Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:56:32.923086Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-14T16:56:32.92315Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:56:32.923163Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-14T16:56:32.92318Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T16:56:32.92424Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-07-14T16:56:32.924313Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-14T16:56:32.926669Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.136333ms"} {"level":"info","ts":"2026-07-14T16:56:32.931017Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-07-14T16:56:32.931072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T16:56:32.931087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-07-14T16:56:32.931096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T16:56:32.931104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-07-14T16:56:32.931136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-07-14T16:56:32.934555Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T16:56:32.935642Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T16:56:32.936799Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-14T16:56:32.93854Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T16:56:32.939199Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T16:56:32.939654Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:56:32.939744Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:56:32.939753Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:56:32.939841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-07-14T16:56:32.941266Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-07-14T16:56:32.942487Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T16:56:32.942561Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-14T16:56:32.944619Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-07-14T16:56:32.944809Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T16:56:32.944844Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T16:56:33.031608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T16:56:33.031679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T16:56:33.031711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-07-14T16:56:33.031738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-07-14T16:56:33.031754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-14T16:56:33.031775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-07-14T16:56:33.031793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-07-14T16:56:33.03274Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:56:33.034144Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:56:33.034274Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:56:33.034311Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:56:33.034375Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:56:33.034414Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:56:33.034488Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T16:56:33.034766Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T16:56:33.034792Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T16:56:33.037466Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-07-14T16:56:33.038027Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-14T16:56:38.671997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.096208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-14T16:56:38.672117Z","caller":"traceutil/trace.go:171","msg":"trace[1238153663] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:269; }","duration":"117.290712ms","start":"2026-07-14T16:56:38.554795Z","end":"2026-07-14T16:56:38.672086Z","steps":["trace[1238153663] 'range keys from in-memory index tree' (duration: 117.011876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:38.672496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.130017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:56:38.672658Z","caller":"traceutil/trace.go:171","msg":"trace[1430117622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:269; }","duration":"129.333192ms","start":"2026-07-14T16:56:38.543303Z","end":"2026-07-14T16:56:38.672636Z","steps":["trace[1430117622] 'range keys from in-memory index tree' (duration: 129.051793ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:38.769621Z","caller":"traceutil/trace.go:171","msg":"trace[1197233861] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"162.12593ms","start":"2026-07-14T16:56:38.607472Z","end":"2026-07-14T16:56:38.769598Z","steps":["trace[1197233861] 'process raft request' (duration: 162.053298ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:38.891053Z","caller":"traceutil/trace.go:171","msg":"trace[140124653] linearizableReadLoop","detail":"{readStateIndex:283; appliedIndex:280; }","duration":"278.07726ms","start":"2026-07-14T16:56:38.61296Z","end":"2026-07-14T16:56:38.891037Z","steps":["trace[140124653] 'read index received' (duration: 156.649404ms)","trace[140124653] 'applied index is now lower than readState.Index' (duration: 121.427276ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:56:38.891058Z","caller":"traceutil/trace.go:171","msg":"trace[79243268] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"282.729375ms","start":"2026-07-14T16:56:38.608302Z","end":"2026-07-14T16:56:38.891032Z","steps":["trace[79243268] 'process raft request' (duration: 282.615493ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:38.891096Z","caller":"traceutil/trace.go:171","msg":"trace[211500221] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"283.577106ms","start":"2026-07-14T16:56:38.607491Z","end":"2026-07-14T16:56:38.891068Z","steps":["trace[211500221] 'process raft request' (duration: 283.372271ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:38.891126Z","caller":"traceutil/trace.go:171","msg":"trace[16251845] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"281.27726ms","start":"2026-07-14T16:56:38.609844Z","end":"2026-07-14T16:56:38.891121Z","steps":["trace[16251845] 'process raft request' (duration: 281.096955ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:38.891209Z","caller":"traceutil/trace.go:171","msg":"trace[853599623] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"214.056841ms","start":"2026-07-14T16:56:38.677144Z","end":"2026-07-14T16:56:38.891201Z","steps":["trace[853599623] 'process raft request' (duration: 213.806135ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:38.89128Z","caller":"traceutil/trace.go:171","msg":"trace[1546493932] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"199.780709ms","start":"2026-07-14T16:56:38.691494Z","end":"2026-07-14T16:56:38.891275Z","steps":["trace[1546493932] 'process raft request' (duration: 199.515503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:38.891365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.396699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T16:56:38.891384Z","caller":"traceutil/trace.go:171","msg":"trace[58741756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:271; }","duration":"278.43908ms","start":"2026-07-14T16:56:38.612939Z","end":"2026-07-14T16:56:38.891378Z","steps":["trace[58741756] 'agreement among raft nodes before linearized reading' (duration: 278.392989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:38.891475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.851784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:56:38.891491Z","caller":"traceutil/trace.go:171","msg":"trace[818693627] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:271; }","duration":"217.896925ms","start":"2026-07-14T16:56:38.673589Z","end":"2026-07-14T16:56:38.891486Z","steps":["trace[818693627] 'agreement among raft nodes before linearized reading' (duration: 217.873245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:38.891569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.933285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:56:38.891586Z","caller":"traceutil/trace.go:171","msg":"trace[1809754082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:271; }","duration":"208.966396ms","start":"2026-07-14T16:56:38.682615Z","end":"2026-07-14T16:56:38.891581Z","steps":["trace[1809754082] 'agreement among raft nodes before linearized reading' (duration: 208.939425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:38.891584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.919073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-07-14T16:56:38.89163Z","caller":"traceutil/trace.go:171","msg":"trace[1066550947] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:271; }","duration":"120.988275ms","start":"2026-07-14T16:56:38.770627Z","end":"2026-07-14T16:56:38.891615Z","steps":["trace[1066550947] 'agreement among raft nodes before linearized reading' (duration: 120.889202ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:39.12119Z","caller":"traceutil/trace.go:171","msg":"trace[1013453003] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"224.021016ms","start":"2026-07-14T16:56:38.897141Z","end":"2026-07-14T16:56:39.121162Z","steps":["trace[1013453003] 'process raft request' (duration: 216.271495ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:39.154052Z","caller":"traceutil/trace.go:171","msg":"trace[1303928928] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:285; }","duration":"256.827804ms","start":"2026-07-14T16:56:38.897207Z","end":"2026-07-14T16:56:39.154035Z","steps":["trace[1303928928] 'read index received' (duration: 216.214543ms)","trace[1303928928] 'applied index is now lower than readState.Index' (duration: 40.612221ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:56:39.154296Z","caller":"traceutil/trace.go:171","msg":"trace[973837992] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"257.120323ms","start":"2026-07-14T16:56:38.89716Z","end":"2026-07-14T16:56:39.15428Z","steps":["trace[973837992] 'process raft request' (duration: 256.809825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:39.154587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.150444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-07-14T16:56:39.154604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.534393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-07-14T16:56:39.154666Z","caller":"traceutil/trace.go:171","msg":"trace[1887046505] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:273; }","duration":"255.237126ms","start":"2026-07-14T16:56:38.899409Z","end":"2026-07-14T16:56:39.154647Z","steps":["trace[1887046505] 'agreement among raft nodes before linearized reading' (duration: 255.079182ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:39.154679Z","caller":"traceutil/trace.go:171","msg":"trace[1839292888] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:273; }","duration":"257.654575ms","start":"2026-07-14T16:56:38.897004Z","end":"2026-07-14T16:56:39.154659Z","steps":["trace[1839292888] 'agreement among raft nodes before linearized reading' (duration: 257.478871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:57:14.445587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.391732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:57:14.445692Z","caller":"traceutil/trace.go:171","msg":"trace[1593619569] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"276.557544ms","start":"2026-07-14T16:57:14.169118Z","end":"2026-07-14T16:57:14.445676Z","steps":["trace[1593619569] 'read index received' (duration: 111.511606ms)","trace[1593619569] 'applied index is now lower than readState.Index' (duration: 165.044728ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:57:14.445781Z","caller":"traceutil/trace.go:171","msg":"trace[168441659] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"404.552397ms","start":"2026-07-14T16:57:14.041171Z","end":"2026-07-14T16:57:14.445724Z","steps":["trace[168441659] 'process raft request' (duration: 239.449638ms)","trace[168441659] 'compare' (duration: 164.262849ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:57:14.44594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.812851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T16:57:14.445972Z","caller":"traceutil/trace.go:171","msg":"trace[1379983560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"276.879063ms","start":"2026-07-14T16:57:14.169083Z","end":"2026-07-14T16:57:14.445962Z","steps":["trace[1379983560] 'agreement among raft nodes before linearized reading' (duration: 276.689587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:57:14.445962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:57:14.041142Z","time spent":"404.736043ms","remote":"127.0.0.1:50792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:57:14.446015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.443821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T16:57:14.44607Z","caller":"traceutil/trace.go:171","msg":"trace[56543024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"267.544243ms","start":"2026-07-14T16:57:14.178513Z","end":"2026-07-14T16:57:14.446057Z","steps":["trace[56543024] 'agreement among raft nodes before linearized reading' (duration: 267.297227ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:08.799013Z","caller":"traceutil/trace.go:171","msg":"trace[1640178321] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"101.890784ms","start":"2026-07-14T16:58:08.697104Z","end":"2026-07-14T16:58:08.798995Z","steps":["trace[1640178321] 'read index received' (duration: 101.7373ms)","trace[1640178321] 'applied index is now lower than readState.Index' (duration: 150.704µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:58:08.799111Z","caller":"traceutil/trace.go:171","msg":"trace[1616304046] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"104.148659ms","start":"2026-07-14T16:58:08.694915Z","end":"2026-07-14T16:58:08.799064Z","steps":["trace[1616304046] 'process raft request' (duration: 103.974934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:08.799187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.070398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T16:58:08.799222Z","caller":"traceutil/trace.go:171","msg":"trace[1649062787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:980; }","duration":"102.133819ms","start":"2026-07-14T16:58:08.697073Z","end":"2026-07-14T16:58:08.799207Z","steps":["trace[1649062787] 'agreement among raft nodes before linearized reading' (duration: 102.018856ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:08.937118Z","caller":"traceutil/trace.go:171","msg":"trace[1538347130] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"134.030069ms","start":"2026-07-14T16:58:08.803067Z","end":"2026-07-14T16:58:08.937097Z","steps":["trace[1538347130] 'process raft request' (duration: 105.76102ms)","trace[1538347130] 'compare' (duration: 28.197158ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:58:09.194492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.482042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:58:09.194641Z","caller":"traceutil/trace.go:171","msg":"trace[1053257109] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"241.371737ms","start":"2026-07-14T16:58:08.953251Z","end":"2026-07-14T16:58:09.194623Z","steps":["trace[1053257109] 'process raft request' (duration: 112.538316ms)","trace[1053257109] 'compare' (duration: 128.371419ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:58:09.194856Z","caller":"traceutil/trace.go:171","msg":"trace[561273792] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"124.706158ms","start":"2026-07-14T16:58:09.070124Z","end":"2026-07-14T16:58:09.19483Z","steps":["trace[561273792] 'process raft request' (duration: 124.465112ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:31.41895Z","caller":"traceutil/trace.go:171","msg":"trace[1766848309] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"133.31689ms","start":"2026-07-14T16:58:31.285607Z","end":"2026-07-14T16:58:31.418924Z","steps":["trace[1766848309] 'process raft request' (duration: 133.180447ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:47.727984Z","caller":"traceutil/trace.go:171","msg":"trace[596629540] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"181.556355ms","start":"2026-07-14T16:58:47.546405Z","end":"2026-07-14T16:58:47.727961Z","steps":["trace[596629540] 'read index received' (duration: 181.383241ms)","trace[596629540] 'applied index is now lower than readState.Index' (duration: 171.954µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:58:47.728119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.70287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:47.728114Z","caller":"traceutil/trace.go:171","msg":"trace[1897318233] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"253.919687ms","start":"2026-07-14T16:58:47.474154Z","end":"2026-07-14T16:58:47.728073Z","steps":["trace[1897318233] 'process raft request' (duration: 253.661751ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:47.728152Z","caller":"traceutil/trace.go:171","msg":"trace[827608466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1158; }","duration":"181.771281ms","start":"2026-07-14T16:58:47.54637Z","end":"2026-07-14T16:58:47.728141Z","steps":["trace[827608466] 'agreement among raft nodes before linearized reading' (duration: 181.709179ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:47.88542Z","caller":"traceutil/trace.go:171","msg":"trace[1971679937] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"157.338356ms","start":"2026-07-14T16:58:47.728051Z","end":"2026-07-14T16:58:47.885389Z","steps":["trace[1971679937] 'read index received' (duration: 155.193273ms)","trace[1971679937] 'applied index is now lower than readState.Index' (duration: 2.144112ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:58:47.885523Z","caller":"traceutil/trace.go:171","msg":"trace[783955814] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"289.343874ms","start":"2026-07-14T16:58:47.596156Z","end":"2026-07-14T16:58:47.8855Z","steps":["trace[783955814] 'process raft request' (duration: 287.10373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:47.885723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.316089ms","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":"warn","ts":"2026-07-14T16:58:47.885713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.42103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T16:58:47.885764Z","caller":"traceutil/trace.go:171","msg":"trace[1938823918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"225.408751ms","start":"2026-07-14T16:58:47.660343Z","end":"2026-07-14T16:58:47.885752Z","steps":["trace[1938823918] 'agreement among raft nodes before linearized reading' (duration: 225.187576ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:47.88579Z","caller":"traceutil/trace.go:171","msg":"trace[944581601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"193.526712ms","start":"2026-07-14T16:58:47.692241Z","end":"2026-07-14T16:58:47.885768Z","steps":["trace[944581601] 'agreement among raft nodes before linearized reading' (duration: 193.278356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:58:47.885942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.644368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:58:47.885945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.381965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:58:47.885983Z","caller":"traceutil/trace.go:171","msg":"trace[260430788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"156.6989ms","start":"2026-07-14T16:58:47.729271Z","end":"2026-07-14T16:58:47.88597Z","steps":["trace[260430788] 'agreement among raft nodes before linearized reading' (duration: 156.626068ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:58:47.886003Z","caller":"traceutil/trace.go:171","msg":"trace[2098291202] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1159; }","duration":"186.485238ms","start":"2026-07-14T16:58:47.699502Z","end":"2026-07-14T16:58:47.885987Z","steps":["trace[2098291202] 'agreement among raft nodes before linearized reading' (duration: 186.367665ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:05.786623Z","caller":"traceutil/trace.go:171","msg":"trace[1351454972] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"109.679725ms","start":"2026-07-14T16:59:05.67693Z","end":"2026-07-14T16:59:05.78661Z","steps":["trace[1351454972] 'process raft request' (duration: 109.589812ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:05.894262Z","caller":"traceutil/trace.go:171","msg":"trace[342207040] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"113.305866ms","start":"2026-07-14T16:59:05.780925Z","end":"2026-07-14T16:59:05.894231Z","steps":["trace[342207040] 'process raft request' (duration: 102.026494ms)","trace[342207040] 'compare' (duration: 11.166869ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:05.917255Z","caller":"traceutil/trace.go:171","msg":"trace[98907487] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"123.703944ms","start":"2026-07-14T16:59:05.793529Z","end":"2026-07-14T16:59:05.917233Z","steps":["trace[98907487] 'process raft request' (duration: 123.54383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:06.118314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.09331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:59:06.11851Z","caller":"traceutil/trace.go:171","msg":"trace[1880504288] linearizableReadLoop","detail":"{readStateIndex:1296; appliedIndex:1294; }","duration":"106.818133ms","start":"2026-07-14T16:59:06.011674Z","end":"2026-07-14T16:59:06.118492Z","steps":["trace[1880504288] 'read index received' (duration: 49.811µs)","trace[1880504288] 'applied index is now lower than readState.Index' (duration: 106.767452ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:06.118545Z","caller":"traceutil/trace.go:171","msg":"trace[510826946] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"197.077083ms","start":"2026-07-14T16:59:05.921427Z","end":"2026-07-14T16:59:06.118504Z","steps":["trace[510826946] 'process raft request' (duration: 85.727927ms)","trace[510826946] 'compare' (duration: 110.971237ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:06.118824Z","caller":"traceutil/trace.go:171","msg":"trace[1808929212] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"193.987206ms","start":"2026-07-14T16:59:05.924824Z","end":"2026-07-14T16:59:06.118811Z","steps":["trace[1808929212] 'process raft request' (duration: 193.602477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:06.119085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.412178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T16:59:06.119123Z","caller":"traceutil/trace.go:171","msg":"trace[1672742436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1244; }","duration":"107.48977ms","start":"2026-07-14T16:59:06.011623Z","end":"2026-07-14T16:59:06.119113Z","steps":["trace[1672742436] 'agreement among raft nodes before linearized reading' (duration: 107.365427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:06.119218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.776867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-14T16:59:06.119266Z","caller":"traceutil/trace.go:171","msg":"trace[587119384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1244; }","duration":"101.867799ms","start":"2026-07-14T16:59:06.017386Z","end":"2026-07-14T16:59:06.119254Z","steps":["trace[587119384] 'agreement among raft nodes before linearized reading' (duration: 101.669354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:06.119573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.575052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T16:59:06.119612Z","caller":"traceutil/trace.go:171","msg":"trace[959022896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1244; }","duration":"101.647644ms","start":"2026-07-14T16:59:06.017955Z","end":"2026-07-14T16:59:06.119603Z","steps":["trace[959022896] 'agreement among raft nodes before linearized reading' (duration: 101.558762ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:06.318875Z","caller":"traceutil/trace.go:171","msg":"trace[1981879768] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"195.454572ms","start":"2026-07-14T16:59:06.123393Z","end":"2026-07-14T16:59:06.318848Z","steps":["trace[1981879768] 'process raft request' (duration: 195.116574ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:06.318989Z","caller":"traceutil/trace.go:171","msg":"trace[932446821] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"194.798017ms","start":"2026-07-14T16:59:06.124147Z","end":"2026-07-14T16:59:06.318945Z","steps":["trace[932446821] 'process raft request' (duration: 194.515589ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:06.319086Z","caller":"traceutil/trace.go:171","msg":"trace[870152009] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"195.263198ms","start":"2026-07-14T16:59:06.12379Z","end":"2026-07-14T16:59:06.319053Z","steps":["trace[870152009] 'process raft request' (duration: 194.835856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.500075Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.821566512s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T17:03:10.500561Z","caller":"traceutil/trace.go:171","msg":"trace[435430044] linearizableReadLoop","detail":"{readStateIndex:2488; appliedIndex:2487; }","duration":"488.463636ms","start":"2026-07-14T17:03:10.012067Z","end":"2026-07-14T17:03:10.500531Z","steps":["trace[435430044] 'read index received' (duration: 488.207879ms)","trace[435430044] 'applied index is now lower than readState.Index' (duration: 254.727µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:10.500646Z","caller":"traceutil/trace.go:171","msg":"trace[283875286] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"1.822169676s","start":"2026-07-14T17:03:08.678433Z","end":"2026-07-14T17:03:10.500602Z","steps":["trace[283875286] 'process raft request' (duration: 1.821956091s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.500832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.6646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-14T17:03:10.500834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:08.678415Z","time spent":"1.822320181s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:03:10.500869Z","caller":"traceutil/trace.go:171","msg":"trace[1874286703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2387; }","duration":"488.758933ms","start":"2026-07-14T17:03:10.012099Z","end":"2026-07-14T17:03:10.500858Z","steps":["trace[1874286703] 'agreement among raft nodes before linearized reading' (duration: 488.603609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.500854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.777438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-14T17:03:10.500854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.771463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"warn","ts":"2026-07-14T17:03:10.500906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.012095Z","time spent":"488.802634ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-14T17:03:10.50092Z","caller":"traceutil/trace.go:171","msg":"trace[1715343924] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2387; }","duration":"487.85205ms","start":"2026-07-14T17:03:10.013048Z","end":"2026-07-14T17:03:10.5009Z","steps":["trace[1715343924] 'agreement among raft nodes before linearized reading' (duration: 487.683146ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:10.500925Z","caller":"traceutil/trace.go:171","msg":"trace[1454505756] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2387; }","duration":"488.871685ms","start":"2026-07-14T17:03:10.012037Z","end":"2026-07-14T17:03:10.500909Z","steps":["trace[1454505756] 'agreement among raft nodes before linearized reading' (duration: 488.635989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.500946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.500922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T17:03:10.500981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.012022Z","time spent":"488.947777ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-14T17:03:10.500992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.004954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:10.501019Z","caller":"traceutil/trace.go:171","msg":"trace[1927838505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2387; }","duration":"488.038204ms","start":"2026-07-14T17:03:10.012972Z","end":"2026-07-14T17:03:10.50101Z","steps":["trace[1927838505] 'agreement among raft nodes before linearized reading' (duration: 488.002114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.500968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.013045Z","time spent":"487.910401ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-14T17:03:10.501044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.012967Z","time spent":"488.071695ms","remote":"127.0.0.1:50284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:03:10.50107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.639739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T17:03:10.501111Z","caller":"traceutil/trace.go:171","msg":"trace[646127295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2387; }","duration":"315.711812ms","start":"2026-07-14T17:03:10.185386Z","end":"2026-07-14T17:03:10.501098Z","steps":["trace[646127295] 'agreement among raft nodes before linearized reading' (duration: 315.606228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.501146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.185369Z","time spent":"315.767853ms","remote":"127.0.0.1:50522","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-07-14T17:03:10.501162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.859366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:03:10.501161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.895557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T17:03:10.501198Z","caller":"traceutil/trace.go:171","msg":"trace[1359749464] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2387; }","duration":"488.906727ms","start":"2026-07-14T17:03:10.01228Z","end":"2026-07-14T17:03:10.501186Z","steps":["trace[1359749464] 'agreement among raft nodes before linearized reading' (duration: 488.851965ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:10.501208Z","caller":"traceutil/trace.go:171","msg":"trace[897103362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2387; }","duration":"488.965349ms","start":"2026-07-14T17:03:10.01223Z","end":"2026-07-14T17:03:10.501195Z","steps":["trace[897103362] 'agreement among raft nodes before linearized reading' (duration: 488.846925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.501232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.012274Z","time spent":"488.949278ms","remote":"127.0.0.1:54016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:03:10.50125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.012211Z","time spent":"489.02431ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:03:10.501318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.293831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:10.501011Z","caller":"traceutil/trace.go:171","msg":"trace[940509455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2387; }","duration":"226.610385ms","start":"2026-07-14T17:03:10.274383Z","end":"2026-07-14T17:03:10.500993Z","steps":["trace[940509455] 'agreement among raft nodes before linearized reading' (duration: 226.41884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.501336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.162303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-14T17:03:10.501353Z","caller":"traceutil/trace.go:171","msg":"trace[1321687464] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2387; }","duration":"484.355823ms","start":"2026-07-14T17:03:10.016987Z","end":"2026-07-14T17:03:10.501343Z","steps":["trace[1321687464] 'agreement among raft nodes before linearized reading' (duration: 484.303921ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:10.501366Z","caller":"traceutil/trace.go:171","msg":"trace[1723081166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2387; }","duration":"489.200544ms","start":"2026-07-14T17:03:10.012157Z","end":"2026-07-14T17:03:10.501357Z","steps":["trace[1723081166] 'agreement among raft nodes before linearized reading' (duration: 489.116332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:10.501384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.016977Z","time spent":"484.402104ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:03:10.501392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:10.012151Z","time spent":"489.234755ms","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:03:34.909511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:35.41038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:35.911222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:36.411871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:36.54548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000283936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-14T17:03:36.545588Z","caller":"traceutil/trace.go:171","msg":"trace[2063762268] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000436921s","start":"2026-07-14T17:03:34.545126Z","end":"2026-07-14T17:03:36.545563Z","steps":["trace[2063762268] 'agreement among raft nodes before linearized reading' (duration: 2.000306936s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:36.545652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.54511Z","time spent":"2.000521182s","remote":"127.0.0.1:50284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:03:36.912849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:37.413771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:37.914463Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:38.414762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:38.547194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000599335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-14T17:03:38.547287Z","caller":"traceutil/trace.go:171","msg":"trace[408094438] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000750148s","start":"2026-07-14T17:03:36.546515Z","end":"2026-07-14T17:03:38.547265Z","steps":["trace[408094438] 'agreement among raft nodes before linearized reading' (duration: 2.000633536s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:38.547335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:36.546493Z","time spent":"2.00083342s","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:03:38.915279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:39.415839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:39.601655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.603643Z","time spent":"4.998003448s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/14 17:03:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:39.625412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999033184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-14T17:03:39.625502Z","caller":"traceutil/trace.go:171","msg":"trace[604742160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999153758s","start":"2026-07-14T17:03:34.626329Z","end":"2026-07-14T17:03:39.625483Z","steps":["trace[604742160] 'agreement among raft nodes before linearized reading' (duration: 4.999052045s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:39.625546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.626317Z","time spent":"4.999219579s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:03:39.63008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999953377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:39.630143Z","caller":"traceutil/trace.go:171","msg":"trace[256930880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000048719s","start":"2026-07-14T17:03:34.630077Z","end":"2026-07-14T17:03:39.630126Z","steps":["trace[256930880] 'agreement among raft nodes before linearized reading' (duration: 4.999970157s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:39.630191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.630067Z","time spent":"5.000111631s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/07/14 17:03:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:39.635349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999442623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-14T17:03:39.635412Z","caller":"traceutil/trace.go:171","msg":"trace[343280088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999548106s","start":"2026-07-14T17:03:34.63585Z","end":"2026-07-14T17:03:39.635398Z","steps":["trace[343280088] 'agreement among raft nodes before linearized reading' (duration: 4.999479464s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:39.635457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.635828Z","time spent":"4.999617479s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/07/14 17:03:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:39.916585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:40.417242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:40.55431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000350758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:40.554397Z","caller":"traceutil/trace.go:171","msg":"trace[1361626375] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000473641s","start":"2026-07-14T17:03:38.553904Z","end":"2026-07-14T17:03:40.554378Z","steps":["trace[1361626375] 'agreement among raft nodes before linearized reading' (duration: 2.000374008s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:40.554438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:38.553891Z","time spent":"2.000539143s","remote":"127.0.0.1:50284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/14 17:03:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:40.918242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:41.358972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.358356Z","time spent":"7.000608353s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-14T17:03:41.410013Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-07-14T17:03:41.410149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.001176757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-07-14T17:03:41.410238Z","caller":"traceutil/trace.go:171","msg":"trace[779143575] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; }","duration":"7.001281809s","start":"2026-07-14T17:03:34.408934Z","end":"2026-07-14T17:03:41.410216Z","steps":["trace[779143575] 'agreement among raft nodes before linearized reading' (duration: 7.001173237s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:41.410288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.408871Z","time spent":"7.001407202s","remote":"127.0.0.1:50620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" "} {"level":"warn","ts":"2026-07-14T17:03:41.910833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:42.411568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:42.555081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000233806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-14T17:03:42.555156Z","caller":"traceutil/trace.go:171","msg":"trace[1652729684] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000361379s","start":"2026-07-14T17:03:40.554774Z","end":"2026-07-14T17:03:42.555135Z","steps":["trace[1652729684] 'agreement among raft nodes before linearized reading' (duration: 2.000267627s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:42.555304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:40.554754Z","time spent":"2.000437422s","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:03:42.912576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:43.413323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:43.913993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:44.41464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:44.565128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000805199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.565227Z","caller":"traceutil/trace.go:171","msg":"trace[800221189] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000958862s","start":"2026-07-14T17:03:42.564245Z","end":"2026-07-14T17:03:44.565204Z","steps":["trace[800221189] 'agreement among raft nodes before linearized reading' (duration: 2.00084232s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.565273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:42.564222Z","time spent":"2.001040295s","remote":"127.0.0.1:50284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.602185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998138832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.602242Z","caller":"traceutil/trace.go:171","msg":"trace[1878580765] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.998235185s","start":"2026-07-14T17:03:39.603994Z","end":"2026-07-14T17:03:44.602229Z","steps":["trace[1878580765] 'agreement among raft nodes before linearized reading' (duration: 4.998168343s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.602278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:39.603978Z","time spent":"4.998291836s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.625956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998200554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.626336Z","caller":"traceutil/trace.go:171","msg":"trace[1755477481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998488582s","start":"2026-07-14T17:03:41.62768Z","end":"2026-07-14T17:03:44.626169Z","steps":["trace[1755477481] 'agreement among raft nodes before linearized reading' (duration: 2.998230835s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.626539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:41.627662Z","time spent":"2.998812799s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.629271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997507237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.629358Z","caller":"traceutil/trace.go:171","msg":"trace[837692677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.997637721s","start":"2026-07-14T17:03:41.631694Z","end":"2026-07-14T17:03:44.629332Z","steps":["trace[837692677] 'agreement among raft nodes before linearized reading' (duration: 2.997547919s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.629393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:41.63167Z","time spent":"2.997715882s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.635447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.99841877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.635523Z","caller":"traceutil/trace.go:171","msg":"trace[795680494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.998498451s","start":"2026-07-14T17:03:41.636996Z","end":"2026-07-14T17:03:44.635495Z","steps":["trace[795680494] 'agreement among raft nodes before linearized reading' (duration: 2.99843638s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.635566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:41.636986Z","time spent":"2.998562524s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.64059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000538931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.640644Z","caller":"traceutil/trace.go:171","msg":"trace[1270964826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"10.000614362s","start":"2026-07-14T17:03:34.640015Z","end":"2026-07-14T17:03:44.64063Z","steps":["trace[1270964826] 'agreement among raft nodes before linearized reading' (duration: 10.000555901s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.640773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.640004Z","time spent":"10.000756537s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.641807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.001156896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.641881Z","caller":"traceutil/trace.go:171","msg":"trace[1640393484] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; }","duration":"10.001255719s","start":"2026-07-14T17:03:34.640604Z","end":"2026-07-14T17:03:44.64186Z","steps":["trace[1640393484] 'agreement among raft nodes before linearized reading' (duration: 10.001168477s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.641936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.640597Z","time spent":"10.00132821s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.642636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000514521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:44.642686Z","caller":"traceutil/trace.go:171","msg":"trace[1239149762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.000585652s","start":"2026-07-14T17:03:34.642088Z","end":"2026-07-14T17:03:44.642674Z","steps":["trace[1239149762] 'agreement among raft nodes before linearized reading' (duration: 10.000531041s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:44.642847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.642081Z","time spent":"10.000754256s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/07/14 17:03:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T17:03:44.915389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:45.415722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342813271905123006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:03:45.430579Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"11.072078825s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-14T17:03:45.55223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:38.551695Z","time spent":"7.000523372s","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-14T17:03:45.554533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.000803277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T17:03:45.5546Z","caller":"traceutil/trace.go:171","msg":"trace[1776417038] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; }","duration":"10.000925951s","start":"2026-07-14T17:03:35.553656Z","end":"2026-07-14T17:03:45.554582Z","steps":["trace[1776417038] 'agreement among raft nodes before linearized reading' (duration: 10.000845299s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.554646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:35.553626Z","time spent":"10.001011202s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} 2026/07/14 17:03:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-14T17:03:45.663288Z","caller":"traceutil/trace.go:171","msg":"trace[1210207850] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2534; }","duration":"4.300080158s","start":"2026-07-14T17:03:41.363165Z","end":"2026-07-14T17:03:45.663245Z","steps":["trace[1210207850] 'process raft request' (duration: 4.299996306s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.663454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:41.363133Z","time spent":"4.300238973s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":570,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:03:45.741412Z","caller":"traceutil/trace.go:171","msg":"trace[1081184915] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"2.347470968s","start":"2026-07-14T17:03:43.393914Z","end":"2026-07-14T17:03:45.741385Z","steps":["trace[1081184915] 'process raft request' (duration: 2.347268793s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:45.741529Z","caller":"traceutil/trace.go:171","msg":"trace[163458976] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"1.11280992s","start":"2026-07-14T17:03:44.628692Z","end":"2026-07-14T17:03:45.741502Z","steps":["trace[163458976] 'process raft request' (duration: 1.112677427s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:45.741545Z","caller":"traceutil/trace.go:171","msg":"trace[34455177] transaction","detail":"{read_only:false; response_revision:2540; number_of_response:1; }","duration":"1.099278621s","start":"2026-07-14T17:03:44.642247Z","end":"2026-07-14T17:03:45.741526Z","steps":["trace[34455177] 'process raft request' (duration: 1.099197859s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:45.74158Z","caller":"traceutil/trace.go:171","msg":"trace[625514904] transaction","detail":"{read_only:false; response_revision:2541; number_of_response:1; }","duration":"1.097034524s","start":"2026-07-14T17:03:44.644534Z","end":"2026-07-14T17:03:45.741568Z","steps":["trace[625514904] 'process raft request' (duration: 1.096945962s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.741597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:44.642232Z","time spent":"1.099339852s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:03:45.741616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:44.644525Z","time spent":"1.097069654s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:03:45.741612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:44.62867Z","time spent":"1.112896523s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:03:45.741595Z","caller":"traceutil/trace.go:171","msg":"trace[1169150162] transaction","detail":"{read_only:false; response_revision:2542; number_of_response:1; }","duration":"184.289335ms","start":"2026-07-14T17:03:45.557277Z","end":"2026-07-14T17:03:45.741567Z","steps":["trace[1169150162] 'process raft request' (duration: 184.244414ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:45.741681Z","caller":"traceutil/trace.go:171","msg":"trace[1680775326] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"1.135432017s","start":"2026-07-14T17:03:44.606242Z","end":"2026-07-14T17:03:45.741674Z","steps":["trace[1680775326] 'process raft request' (duration: 1.135083409s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:45.741706Z","caller":"traceutil/trace.go:171","msg":"trace[1099824724] linearizableReadLoop","detail":"{readStateIndex:2650; appliedIndex:2639; }","duration":"4.33161575s","start":"2026-07-14T17:03:41.410079Z","end":"2026-07-14T17:03:45.741694Z","steps":["trace[1099824724] 'read index received' (duration: 4.020768641s)","trace[1099824724] 'applied index is now lower than readState.Index' (duration: 310.845949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:03:45.741715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:44.606216Z","time spent":"1.135481979s","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:03:45.741747Z","caller":"traceutil/trace.go:171","msg":"trace[1496380780] transaction","detail":"{read_only:false; response_revision:2539; number_of_response:1; }","duration":"1.103688062s","start":"2026-07-14T17:03:44.63805Z","end":"2026-07-14T17:03:45.741738Z","steps":["trace[1496380780] 'process raft request' (duration: 1.103359924s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.741789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:44.63803Z","time spent":"1.103734753s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:03:45.741816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.659549718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:03:45.741843Z","caller":"traceutil/trace.go:171","msg":"trace[1813682076] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2542; }","duration":"4.659618299s","start":"2026-07-14T17:03:41.082217Z","end":"2026-07-14T17:03:45.741835Z","steps":["trace[1813682076] 'agreement among raft nodes before linearized reading' (duration: 4.659539887s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.741873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:41.082198Z","time spent":"4.65966788s","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:03:45.741893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.094790516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-14T17:03:45.741932Z","caller":"traceutil/trace.go:171","msg":"trace[141205231] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2542; }","duration":"9.094842677s","start":"2026-07-14T17:03:36.647077Z","end":"2026-07-14T17:03:45.74192Z","steps":["trace[141205231] 'agreement among raft nodes before linearized reading' (duration: 9.094689574s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.741968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:36.647014Z","time spent":"9.094945919s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-14T17:03:45.742026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.467494047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:03:45.74204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.679508236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:45.742052Z","caller":"traceutil/trace.go:171","msg":"trace[1119663887] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2542; }","duration":"7.467561709s","start":"2026-07-14T17:03:38.274483Z","end":"2026-07-14T17:03:45.742045Z","steps":["trace[1119663887] 'agreement among raft nodes before linearized reading' (duration: 7.467508197s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:45.742067Z","caller":"traceutil/trace.go:171","msg":"trace[691920207] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:0; response_revision:2542; }","duration":"9.679535077s","start":"2026-07-14T17:03:36.062524Z","end":"2026-07-14T17:03:45.742059Z","steps":["trace[691920207] 'agreement among raft nodes before linearized reading' (duration: 9.679490906s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:36.062458Z","time spent":"9.67962875s","remote":"127.0.0.1:50292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.77\" "} {"level":"warn","ts":"2026-07-14T17:03:45.742102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.812910786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:03:45.742101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.588846083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-14T17:03:45.742121Z","caller":"traceutil/trace.go:171","msg":"trace[445182313] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2542; }","duration":"4.812968467s","start":"2026-07-14T17:03:40.929147Z","end":"2026-07-14T17:03:45.742115Z","steps":["trace[445182313] 'agreement among raft nodes before linearized reading' (duration: 4.812936857s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.74214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:40.929129Z","time spent":"4.813005048s","remote":"127.0.0.1:50592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:03:45.742135Z","caller":"traceutil/trace.go:171","msg":"trace[939597017] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2542; }","duration":"7.588918825s","start":"2026-07-14T17:03:38.153204Z","end":"2026-07-14T17:03:45.742123Z","steps":["trace[939597017] 'agreement among raft nodes before linearized reading' (duration: 7.588837872s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.380981596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:03:45.742179Z","caller":"traceutil/trace.go:171","msg":"trace[553254522] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2542; }","duration":"7.381049228s","start":"2026-07-14T17:03:38.361122Z","end":"2026-07-14T17:03:45.742171Z","steps":["trace[553254522] 'agreement among raft nodes before linearized reading' (duration: 7.381005157s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:38.153185Z","time spent":"7.588972865s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-14T17:03:45.742199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:38.3611Z","time spent":"7.381092709s","remote":"127.0.0.1:34092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:03:45.742285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.727603962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-14T17:03:45.742312Z","caller":"traceutil/trace.go:171","msg":"trace[1730218562] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2542; }","duration":"9.727672694s","start":"2026-07-14T17:03:36.014631Z","end":"2026-07-14T17:03:45.742304Z","steps":["trace[1730218562] 'agreement among raft nodes before linearized reading' (duration: 9.727572872s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.813927189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:03:45.742337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:36.014611Z","time spent":"9.727720216s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-14T17:03:45.742349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.289970703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:45.742353Z","caller":"traceutil/trace.go:171","msg":"trace[572799782] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2542; }","duration":"7.813995971s","start":"2026-07-14T17:03:37.928348Z","end":"2026-07-14T17:03:45.742344Z","steps":["trace[572799782] 'agreement among raft nodes before linearized reading' (duration: 7.81394764s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.028712058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:03:45.742384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:37.92833Z","time spent":"7.814046032s","remote":"127.0.0.1:50406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:03:45.742395Z","caller":"traceutil/trace.go:171","msg":"trace[800179962] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2542; }","duration":"8.02876682s","start":"2026-07-14T17:03:37.713621Z","end":"2026-07-14T17:03:45.742388Z","steps":["trace[800179962] 'agreement among raft nodes before linearized reading' (duration: 8.028728249s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.741538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:43.39389Z","time spent":"2.34758204s","remote":"127.0.0.1:50522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:03:45.742413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.313770269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:828"} {"level":"warn","ts":"2026-07-14T17:03:45.742416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:37.713603Z","time spent":"8.028808081s","remote":"127.0.0.1:50670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-07-14T17:03:45.742433Z","caller":"traceutil/trace.go:171","msg":"trace[1159057118] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:2542; }","duration":"11.31381867s","start":"2026-07-14T17:03:34.428608Z","end":"2026-07-14T17:03:45.742427Z","steps":["trace[1159057118] 'agreement among raft nodes before linearized reading' (duration: 11.313778479s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:34.428595Z","time spent":"11.313853642s","remote":"127.0.0.1:50620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":851,"request content":"key:\"/registry/clusterroles/system:controller:replication-controller\" "} {"level":"info","ts":"2026-07-14T17:03:45.742374Z","caller":"traceutil/trace.go:171","msg":"trace[1302577689] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2542; }","duration":"10.289995984s","start":"2026-07-14T17:03:35.452369Z","end":"2026-07-14T17:03:45.742365Z","steps":["trace[1302577689] 'agreement among raft nodes before linearized reading' (duration: 10.289957163s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:38.274459Z","time spent":"7.46760752s","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:03:45.742514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:35.452301Z","time spent":"10.290204839s","remote":"127.0.0.1:53952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:03:45.742571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.26105739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-07-14T17:03:45.742592Z","caller":"traceutil/trace.go:171","msg":"trace[1473848080] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:2542; }","duration":"4.261111612s","start":"2026-07-14T17:03:41.481474Z","end":"2026-07-14T17:03:45.742586Z","steps":["trace[1473848080] 'agreement among raft nodes before linearized reading' (duration: 4.261074021s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.74261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:41.481458Z","time spent":"4.261146772s","remote":"127.0.0.1:50620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":864,"request content":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" "} {"level":"warn","ts":"2026-07-14T17:03:45.742886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.489354998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-14T17:03:45.742922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177441743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:45.742918Z","caller":"traceutil/trace.go:171","msg":"trace[1268367878] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2542; }","duration":"2.48942708s","start":"2026-07-14T17:03:43.253483Z","end":"2026-07-14T17:03:45.74291Z","steps":["trace[1268367878] 'agreement among raft nodes before linearized reading' (duration: 2.489359318s)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:45.742948Z","caller":"traceutil/trace.go:171","msg":"trace[196210778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2542; }","duration":"1.177499885s","start":"2026-07-14T17:03:44.565441Z","end":"2026-07-14T17:03:45.742941Z","steps":["trace[196210778] 'agreement among raft nodes before linearized reading' (duration: 1.177462034s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.952286782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:03:45.742955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:43.253461Z","time spent":"2.489486421s","remote":"127.0.0.1:50462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:03:45.742967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:44.565402Z","time spent":"1.177559066s","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T17:03:45.742987Z","caller":"traceutil/trace.go:171","msg":"trace[939344310] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2542; }","duration":"1.952365815s","start":"2026-07-14T17:03:43.790608Z","end":"2026-07-14T17:03:45.742974Z","steps":["trace[939344310] 'agreement among raft nodes before linearized reading' (duration: 1.952299943s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.742995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.035979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:03:45.743021Z","caller":"traceutil/trace.go:171","msg":"trace[1518155182] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2542; }","duration":"640.06179ms","start":"2026-07-14T17:03:45.102951Z","end":"2026-07-14T17:03:45.743012Z","steps":["trace[1518155182] 'agreement among raft nodes before linearized reading' (duration: 640.022289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:45.743021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:43.790585Z","time spent":"1.952426937s","remote":"127.0.0.1:54156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-14T17:03:45.743042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:03:45.102902Z","time spent":"640.133791ms","remote":"127.0.0.1:54114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "}